{"level":"warn","ts":"2026-03-26T01:56:00.893356Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T01:56:00.893442Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-26T01:56:00.893512Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-26T01:56:00.893524Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-03-26T01:56:00.89354Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-26T01:56:00.894137Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-03-26T01:56:00.894215Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-26T01:56:00.898576Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.156672ms"} {"level":"info","ts":"2026-03-26T01:56:00.905838Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-03-26T01:56:00.905915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-03-26T01:56:00.905935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-03-26T01:56:00.90595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-26T01:56:00.905962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-03-26T01:56:00.906003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-03-26T01:56:00.918013Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-26T01:56:00.920568Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-26T01:56:00.92206Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-26T01:56:00.923862Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-26T01:56:00.924267Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-26T01:56:00.924389Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-26T01:56:00.924473Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-26T01:56:00.924492Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-26T01:56:00.926623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-03-26T01:56:00.926734Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-03-26T01:56:00.928323Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-26T01:56:00.928499Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-03-26T01:56:00.92852Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-03-26T01:56:00.929003Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-26T01:56:00.929079Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-26T01:56:01.906712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-03-26T01:56:01.906767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-26T01:56:01.906806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-03-26T01:56:01.90682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-03-26T01:56:01.906835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-03-26T01:56:01.906846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-03-26T01:56:01.90686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-03-26T01:56:01.907783Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T01:56:01.908588Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-03-26T01:56:01.908595Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T01:56:01.908644Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T01:56:01.908842Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-26T01:56:01.908914Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-26T01:56:01.908994Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T01:56:01.909083Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T01:56:01.909107Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T01:56:01.910994Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-03-26T01:56:01.911197Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-26T01:56:06.490087Z","caller":"traceutil/trace.go:171","msg":"trace[1342907237] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"787.934386ms","start":"2026-03-26T01:56:05.702125Z","end":"2026-03-26T01:56:06.49006Z","steps":["trace[1342907237] 'process raft request' (duration: 787.771522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:06.490807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:05.702083Z","time spent":"788.116081ms","remote":"127.0.0.1:40594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:56:07.006613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.134232ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-26T01:56:07.00671Z","caller":"traceutil/trace.go:171","msg":"trace[305461873] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"511.921775ms","start":"2026-03-26T01:56:06.494776Z","end":"2026-03-26T01:56:07.006697Z","steps":["trace[305461873] 'read index received' (duration: 398.65948ms)","trace[305461873] 'applied index is now lower than readState.Index' (duration: 113.261355ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:56:07.006724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:05.778422Z","time spent":"1.228296225s","remote":"127.0.0.1:40490","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-26T01:56:07.006791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.016948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-tl96o3\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:07.006818Z","caller":"traceutil/trace.go:171","msg":"trace[725919444] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-tl96o3; range_end:; response_count:0; response_revision:212; }","duration":"512.08487ms","start":"2026-03-26T01:56:06.494728Z","end":"2026-03-26T01:56:07.006812Z","steps":["trace[725919444] 'agreement among raft nodes before linearized reading' (duration: 512.035289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:07.006842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:06.494708Z","time spent":"512.127701ms","remote":"127.0.0.1:40506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-tl96o3\" "} {"level":"info","ts":"2026-03-26T01:56:07.355849Z","caller":"traceutil/trace.go:171","msg":"trace[1131510031] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"348.299166ms","start":"2026-03-26T01:56:07.007522Z","end":"2026-03-26T01:56:07.355822Z","steps":["trace[1131510031] 'process raft request' (duration: 347.626908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:07.356064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:07.007509Z","time spent":"348.41072ms","remote":"127.0.0.1:40490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:07.356213Z","caller":"traceutil/trace.go:171","msg":"trace[1155990700] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:218; }","duration":"168.037248ms","start":"2026-03-26T01:56:07.188148Z","end":"2026-03-26T01:56:07.356185Z","steps":["trace[1155990700] 'read index received' (duration: 167.156934ms)","trace[1155990700] 'applied index is now lower than readState.Index' (duration: 879.224µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:56:07.356338Z","caller":"traceutil/trace.go:171","msg":"trace[1056852131] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"343.563699ms","start":"2026-03-26T01:56:07.012764Z","end":"2026-03-26T01:56:07.356328Z","steps":["trace[1056852131] 'process raft request' (duration: 343.301172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:07.356438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:07.012739Z","time spent":"343.635ms","remote":"127.0.0.1:40506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T01:56:07.35651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.562029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T01:56:07.356565Z","caller":"traceutil/trace.go:171","msg":"trace[809681337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:214; }","duration":"111.644232ms","start":"2026-03-26T01:56:07.244906Z","end":"2026-03-26T01:56:07.35655Z","steps":["trace[809681337] 'agreement among raft nodes before linearized reading' (duration: 111.504958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:07.356613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.847395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T01:56:07.356654Z","caller":"traceutil/trace.go:171","msg":"trace[1124773176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:214; }","duration":"108.942459ms","start":"2026-03-26T01:56:07.2477Z","end":"2026-03-26T01:56:07.356642Z","steps":["trace[1124773176] 'agreement among raft nodes before linearized reading' (duration: 108.866256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:07.356632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.468989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:07.356719Z","caller":"traceutil/trace.go:171","msg":"trace[152018060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:214; }","duration":"168.564072ms","start":"2026-03-26T01:56:07.188132Z","end":"2026-03-26T01:56:07.356696Z","steps":["trace[152018060] 'agreement among raft nodes before linearized reading' (duration: 168.392797ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:32.926019Z","caller":"traceutil/trace.go:171","msg":"trace[217846676] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"547.099937ms","start":"2026-03-26T01:56:32.378877Z","end":"2026-03-26T01:56:32.925977Z","steps":["trace[217846676] 'process raft request' (duration: 546.81587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:32.926231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.378858Z","time spent":"547.273451ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:32.954294Z","caller":"traceutil/trace.go:171","msg":"trace[222109824] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"563.717137ms","start":"2026-03-26T01:56:32.390537Z","end":"2026-03-26T01:56:32.954254Z","steps":["trace[222109824] 'process raft request' (duration: 563.321076ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:32.954309Z","caller":"traceutil/trace.go:171","msg":"trace[443687684] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"547.750565ms","start":"2026-03-26T01:56:32.406527Z","end":"2026-03-26T01:56:32.954278Z","steps":["trace[443687684] 'process raft request' (duration: 547.53479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:32.954419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.406512Z","time spent":"547.854938ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:32.95446Z","caller":"traceutil/trace.go:171","msg":"trace[69701411] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"570.170052ms","start":"2026-03-26T01:56:32.384284Z","end":"2026-03-26T01:56:32.954454Z","steps":["trace[69701411] 'process raft request' (duration: 569.34799ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:32.954442Z","caller":"traceutil/trace.go:171","msg":"trace[1201988486] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"412.288978ms","start":"2026-03-26T01:56:32.542129Z","end":"2026-03-26T01:56:32.954418Z","steps":["trace[1201988486] 'process raft request' (duration: 412.214296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:32.954491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.384264Z","time spent":"570.215093ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:32.954567Z","caller":"traceutil/trace.go:171","msg":"trace[1994168336] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"562.394692ms","start":"2026-03-26T01:56:32.392156Z","end":"2026-03-26T01:56:32.954551Z","steps":["trace[1994168336] 'process raft request' (duration: 561.865137ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:32.95461Z","caller":"traceutil/trace.go:171","msg":"trace[537076525] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"535.248787ms","start":"2026-03-26T01:56:32.419353Z","end":"2026-03-26T01:56:32.954601Z","steps":["trace[537076525] 'process raft request' (duration: 534.751804ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:32.954613Z","caller":"traceutil/trace.go:171","msg":"trace[821942570] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"564.061416ms","start":"2026-03-26T01:56:32.390545Z","end":"2026-03-26T01:56:32.954607Z","steps":["trace[821942570] 'process raft request' (duration: 563.263785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:32.954433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.390512Z","time spent":"563.852181ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T01:56:32.954648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.392144Z","time spent":"562.464943ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T01:56:32.954663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.419336Z","time spent":"535.305068ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:32.954593Z","caller":"traceutil/trace.go:171","msg":"trace[1829243413] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"570.234004ms","start":"2026-03-26T01:56:32.384353Z","end":"2026-03-26T01:56:32.954587Z","steps":["trace[1829243413] 'process raft request' (duration: 569.408921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:32.954707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.384346Z","time spent":"570.350147ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T01:56:32.95457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.542091Z","time spent":"412.415292ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:32.954756Z","caller":"traceutil/trace.go:171","msg":"trace[949006659] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"510.761244ms","start":"2026-03-26T01:56:32.44399Z","end":"2026-03-26T01:56:32.954751Z","steps":["trace[949006659] 'process raft request' (duration: 510.253441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:32.954665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.390534Z","time spent":"564.119539ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:32.954768Z","caller":"traceutil/trace.go:171","msg":"trace[618358877] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"531.795504ms","start":"2026-03-26T01:56:32.422944Z","end":"2026-03-26T01:56:32.95474Z","steps":["trace[618358877] 'process raft request' (duration: 531.242009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:32.954881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.42293Z","time spent":"531.897546ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T01:56:32.954782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.443964Z","time spent":"510.809105ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:32.955051Z","caller":"traceutil/trace.go:171","msg":"trace[1709424072] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"535.397721ms","start":"2026-03-26T01:56:32.419403Z","end":"2026-03-26T01:56:32.954801Z","steps":["trace[1709424072] 'process raft request' (duration: 534.743823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:32.955814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:32.419378Z","time spent":"535.72525ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:33.205897Z","caller":"traceutil/trace.go:171","msg":"trace[1497871907] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"214.190526ms","start":"2026-03-26T01:56:32.991676Z","end":"2026-03-26T01:56:33.205866Z","steps":["trace[1497871907] 'process raft request' (duration: 196.347314ms)","trace[1497871907] 'compare' (duration: 17.682568ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:56:33.278998Z","caller":"traceutil/trace.go:171","msg":"trace[1860098783] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"261.709743ms","start":"2026-03-26T01:56:33.017269Z","end":"2026-03-26T01:56:33.278978Z","steps":["trace[1860098783] 'process raft request' (duration: 261.446616ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.279408Z","caller":"traceutil/trace.go:171","msg":"trace[733781569] transaction","detail":"{read_only:false; number_of_response:1; response_revision:545; }","duration":"261.564989ms","start":"2026-03-26T01:56:33.017803Z","end":"2026-03-26T01:56:33.279368Z","steps":["trace[733781569] 'process raft request' (duration: 261.087456ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.279443Z","caller":"traceutil/trace.go:171","msg":"trace[549353049] transaction","detail":"{read_only:false; number_of_response:0; response_revision:545; }","duration":"261.557779ms","start":"2026-03-26T01:56:33.017877Z","end":"2026-03-26T01:56:33.279435Z","steps":["trace[549353049] 'process raft request' (duration: 261.386434ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.279553Z","caller":"traceutil/trace.go:171","msg":"trace[205411292] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"251.101726ms","start":"2026-03-26T01:56:33.028414Z","end":"2026-03-26T01:56:33.279516Z","steps":["trace[205411292] 'process raft request' (duration: 250.914611ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.279607Z","caller":"traceutil/trace.go:171","msg":"trace[1963181657] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"251.075776ms","start":"2026-03-26T01:56:33.028512Z","end":"2026-03-26T01:56:33.279588Z","steps":["trace[1963181657] 'process raft request' (duration: 250.898881ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.279947Z","caller":"traceutil/trace.go:171","msg":"trace[1191042607] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"251.332683ms","start":"2026-03-26T01:56:33.028582Z","end":"2026-03-26T01:56:33.279914Z","steps":["trace[1191042607] 'process raft request' (duration: 250.87994ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.280171Z","caller":"traceutil/trace.go:171","msg":"trace[397689302] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:561; }","duration":"139.332351ms","start":"2026-03-26T01:56:33.140823Z","end":"2026-03-26T01:56:33.280156Z","steps":["trace[397689302] 'read index received' (duration: 47.365162ms)","trace[397689302] 'applied index is now lower than readState.Index' (duration: 91.966639ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:56:33.280236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.411523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:33.280259Z","caller":"traceutil/trace.go:171","msg":"trace[177121911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"139.486215ms","start":"2026-03-26T01:56:33.140767Z","end":"2026-03-26T01:56:33.280254Z","steps":["trace[177121911] 'agreement among raft nodes before linearized reading' (duration: 139.435833ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.280393Z","caller":"traceutil/trace.go:171","msg":"trace[1002067114] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"250.953973ms","start":"2026-03-26T01:56:33.029333Z","end":"2026-03-26T01:56:33.280287Z","steps":["trace[1002067114] 'process raft request' (duration: 250.673495ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.280415Z","caller":"traceutil/trace.go:171","msg":"trace[515795471] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"245.109614ms","start":"2026-03-26T01:56:33.035291Z","end":"2026-03-26T01:56:33.2804Z","steps":["trace[515795471] 'process raft request' (duration: 244.788845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:33.969413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.706628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:56:33.969512Z","caller":"traceutil/trace.go:171","msg":"trace[1212606130] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"688.73998ms","start":"2026-03-26T01:56:33.280757Z","end":"2026-03-26T01:56:33.969497Z","steps":["trace[1212606130] 'read index received' (duration: 162.957751ms)","trace[1212606130] 'applied index is now lower than readState.Index' (duration: 525.780819ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:56:33.969581Z","caller":"traceutil/trace.go:171","msg":"trace[1347690190] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"715.703709ms","start":"2026-03-26T01:56:33.253865Z","end":"2026-03-26T01:56:33.969569Z","steps":["trace[1347690190] 'process raft request' (duration: 189.756545ms)","trace[1347690190] 'compare' (duration: 525.40097ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:56:33.969635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.253835Z","time spent":"715.768111ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:56:33.969843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.08302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-03-26T01:56:33.969873Z","caller":"traceutil/trace.go:171","msg":"trace[1369130954] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:551; }","duration":"689.13237ms","start":"2026-03-26T01:56:33.28073Z","end":"2026-03-26T01:56:33.969862Z","steps":["trace[1369130954] 'agreement among raft nodes before linearized reading' (duration: 688.999877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:33.969901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.280715Z","time spent":"689.179162ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1036,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-03-26T01:56:33.970822Z","caller":"traceutil/trace.go:171","msg":"trace[780282217] transaction","detail":"{read_only:false; number_of_response:1; response_revision:552; }","duration":"682.740898ms","start":"2026-03-26T01:56:33.288055Z","end":"2026-03-26T01:56:33.970796Z","steps":["trace[780282217] 'process raft request' (duration: 682.570413ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.970947Z","caller":"traceutil/trace.go:171","msg":"trace[363560031] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"619.262949ms","start":"2026-03-26T01:56:33.351666Z","end":"2026-03-26T01:56:33.970929Z","steps":["trace[363560031] 'process raft request' (duration: 619.160046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:33.971036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.288047Z","time spent":"682.893842ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7375,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:56:33.971046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.351637Z","time spent":"619.359532ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T01:56:33.971205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.124737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:33.971256Z","caller":"traceutil/trace.go:171","msg":"trace[979391566] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"680.115437ms","start":"2026-03-26T01:56:33.291128Z","end":"2026-03-26T01:56:33.971243Z","steps":["trace[979391566] 'process raft request' (duration: 679.554882ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.971263Z","caller":"traceutil/trace.go:171","msg":"trace[1716564513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"690.19486ms","start":"2026-03-26T01:56:33.281053Z","end":"2026-03-26T01:56:33.971248Z","steps":["trace[1716564513] 'agreement among raft nodes before linearized reading' (duration: 690.090197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:33.971305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.281041Z","time spent":"690.253951ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T01:56:33.971329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.291114Z","time spent":"680.170228ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:56:33.971395Z","caller":"traceutil/trace.go:171","msg":"trace[743240342] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"685.469901ms","start":"2026-03-26T01:56:33.285908Z","end":"2026-03-26T01:56:33.971378Z","steps":["trace[743240342] 'process raft request' (duration: 684.596998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:33.971483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.285886Z","time spent":"685.547224ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:56:33.971689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.012025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T01:56:33.971771Z","caller":"traceutil/trace.go:171","msg":"trace[1074989822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"407.119108ms","start":"2026-03-26T01:56:33.564623Z","end":"2026-03-26T01:56:33.971742Z","steps":["trace[1074989822] 'agreement among raft nodes before linearized reading' (duration: 407.007935ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:33.971805Z","caller":"traceutil/trace.go:171","msg":"trace[737425014] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"679.1431ms","start":"2026-03-26T01:56:33.292644Z","end":"2026-03-26T01:56:33.971787Z","steps":["trace[737425014] 'process raft request' (duration: 678.105212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:33.971826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.564606Z","time spent":"407.207041ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T01:56:33.971878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.292635Z","time spent":"679.215572ms","remote":"127.0.0.1:40906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T01:56:33.971947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.773781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T01:56:33.972033Z","caller":"traceutil/trace.go:171","msg":"trace[651285534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"402.879384ms","start":"2026-03-26T01:56:33.569138Z","end":"2026-03-26T01:56:33.972017Z","steps":["trace[651285534] 'agreement among raft nodes before linearized reading' (duration: 402.7601ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:33.972086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.569127Z","time spent":"402.945655ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T01:56:33.971986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.115875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:56:33.972165Z","caller":"traceutil/trace.go:171","msg":"trace[1367420000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"405.302809ms","start":"2026-03-26T01:56:33.566844Z","end":"2026-03-26T01:56:33.972147Z","steps":["trace[1367420000] 'agreement among raft nodes before linearized reading' (duration: 405.110964ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:33.972223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.566833Z","time spent":"405.371912ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T01:56:33.9717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.025336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-26T01:56:33.972348Z","caller":"traceutil/trace.go:171","msg":"trace[1717790319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"407.715744ms","start":"2026-03-26T01:56:33.564623Z","end":"2026-03-26T01:56:33.972339Z","steps":["trace[1717790319] 'agreement among raft nodes before linearized reading' (duration: 407.027706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:33.97238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:33.564604Z","time spent":"407.765916ms","remote":"127.0.0.1:40684","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":"info","ts":"2026-03-26T01:56:39.872306Z","caller":"traceutil/trace.go:171","msg":"trace[1174457366] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"320.443303ms","start":"2026-03-26T01:56:39.551836Z","end":"2026-03-26T01:56:39.872279Z","steps":["trace[1174457366] 'process raft request' (duration: 320.237968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:39.872454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:39.551818Z","time spent":"320.563666ms","remote":"127.0.0.1:40606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:56:40.377546Z","caller":"traceutil/trace.go:171","msg":"trace[63627639] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"379.912782ms","start":"2026-03-26T01:56:39.997599Z","end":"2026-03-26T01:56:40.377512Z","steps":["trace[63627639] 'read index received' (duration: 378.978837ms)","trace[63627639] 'applied index is now lower than readState.Index' (duration: 932.245µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:56:40.377619Z","caller":"traceutil/trace.go:171","msg":"trace[1051681190] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"745.360783ms","start":"2026-03-26T01:56:39.632233Z","end":"2026-03-26T01:56:40.377593Z","steps":["trace[1051681190] 'process raft request' (duration: 744.669115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:40.377741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:39.632211Z","time spent":"745.466526ms","remote":"127.0.0.1:40506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:56:40.377798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.183339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T01:56:40.377952Z","caller":"traceutil/trace.go:171","msg":"trace[1963762518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"380.389706ms","start":"2026-03-26T01:56:39.997546Z","end":"2026-03-26T01:56:40.377936Z","steps":["trace[1963762518] 'agreement among raft nodes before linearized reading' (duration: 380.067877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:40.37799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:39.997529Z","time spent":"380.452947ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T01:56:41.121446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.99835ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-26T01:56:41.121667Z","caller":"traceutil/trace.go:171","msg":"trace[1057855191] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"744.019786ms","start":"2026-03-26T01:56:40.377627Z","end":"2026-03-26T01:56:41.121647Z","steps":["trace[1057855191] 'read index received' (duration: 4.771369ms)","trace[1057855191] 'applied index is now lower than readState.Index' (duration: 739.246717ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:56:41.121748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.656101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:41.12178Z","caller":"traceutil/trace.go:171","msg":"trace[448436279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"980.726443ms","start":"2026-03-26T01:56:40.141038Z","end":"2026-03-26T01:56:41.121764Z","steps":["trace[448436279] 'agreement among raft nodes before linearized reading' (duration: 980.678291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:41.121808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:40.141018Z","time spent":"980.782594ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T01:56:41.121818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117373781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-26T01:56:41.121861Z","caller":"traceutil/trace.go:171","msg":"trace[1834016329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.117452453s","start":"2026-03-26T01:56:40.004394Z","end":"2026-03-26T01:56:41.121846Z","steps":["trace[1834016329] 'agreement among raft nodes before linearized reading' (duration: 1.117283818s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:41.121903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:40.004341Z","time spent":"1.117551496s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T01:56:41.122018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116875068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T01:56:41.122105Z","caller":"traceutil/trace.go:171","msg":"trace[1788968017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"1.11699565s","start":"2026-03-26T01:56:40.005088Z","end":"2026-03-26T01:56:41.122084Z","steps":["trace[1788968017] 'agreement among raft nodes before linearized reading' (duration: 1.116570209s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:41.122194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:40.00508Z","time spent":"1.117100724s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T01:56:41.122322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123383033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:56:41.122473Z","caller":"traceutil/trace.go:171","msg":"trace[1670389266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"1.123544228s","start":"2026-03-26T01:56:39.998913Z","end":"2026-03-26T01:56:41.122457Z","steps":["trace[1670389266] 'agreement among raft nodes before linearized reading' (duration: 1.122783008s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:41.12257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:39.998907Z","time spent":"1.123650771s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-26T01:56:41.123282Z","caller":"traceutil/trace.go:171","msg":"trace[670927757] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"741.179959ms","start":"2026-03-26T01:56:40.38208Z","end":"2026-03-26T01:56:41.12326Z","steps":["trace[670927757] 'process raft request' (duration: 740.588033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:41.123734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:40.382061Z","time spent":"741.409116ms","remote":"127.0.0.1:40684","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-03-26T01:56:41.624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604711624871716,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T01:56:42.124485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604711624871716,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T01:56:42.133163Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.686121414s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-26T01:56:42.239463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.882805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:56:42.239797Z","caller":"traceutil/trace.go:171","msg":"trace[791536836] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.810058969s","start":"2026-03-26T01:56:40.429705Z","end":"2026-03-26T01:56:42.239764Z","steps":["trace[791536836] 'process raft request' (duration: 1.703733591s)","trace[791536836] 'compare' (duration: 105.148246ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:56:42.239978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:40.429677Z","time spent":"1.810244444s","remote":"127.0.0.1:40888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:42.240994Z","caller":"traceutil/trace.go:171","msg":"trace[958556724] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:655; }","duration":"1.117706711s","start":"2026-03-26T01:56:41.123133Z","end":"2026-03-26T01:56:42.24084Z","steps":["trace[958556724] 'read index received' (duration: 1.010206811s)","trace[958556724] 'applied index is now lower than readState.Index' (duration: 107.49823ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:56:42.241099Z","caller":"traceutil/trace.go:171","msg":"trace[1949699657] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.114379911s","start":"2026-03-26T01:56:41.126708Z","end":"2026-03-26T01:56:42.241088Z","steps":["trace[1949699657] 'process raft request' (duration: 1.114046952s)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.241084Z","caller":"traceutil/trace.go:171","msg":"trace[1071162036] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.115529682s","start":"2026-03-26T01:56:41.125532Z","end":"2026-03-26T01:56:42.241062Z","steps":["trace[1071162036] 'process raft request' (duration: 1.115147221s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.241174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:41.126702Z","time spent":"1.114425842s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:56:42.241212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:41.125518Z","time spent":"1.115619625s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:56:42.24127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.837327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:56:42.241308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.800036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:56:42.241365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.898029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:56:42.241363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.414864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.241379Z","caller":"traceutil/trace.go:171","msg":"trace[726393835] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:636; }","duration":"227.890338ms","start":"2026-03-26T01:56:42.013472Z","end":"2026-03-26T01:56:42.241362Z","steps":["trace[726393835] 'agreement among raft nodes before linearized reading' (duration: 227.772525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.241495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.436706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:56:42.241485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.493906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:56:42.241518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.492868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.241543Z","caller":"traceutil/trace.go:171","msg":"trace[582692286] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:636; }","duration":"271.540499ms","start":"2026-03-26T01:56:41.969993Z","end":"2026-03-26T01:56:42.241534Z","steps":["trace[582692286] 'agreement among raft nodes before linearized reading' (duration: 271.511999ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.241561Z","caller":"traceutil/trace.go:171","msg":"trace[966870422] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:636; }","duration":"248.577818ms","start":"2026-03-26T01:56:41.992971Z","end":"2026-03-26T01:56:42.241548Z","steps":["trace[966870422] 'agreement among raft nodes before linearized reading' (duration: 248.490216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.241584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.175645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.241632Z","caller":"traceutil/trace.go:171","msg":"trace[109919598] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:636; }","duration":"320.235778ms","start":"2026-03-26T01:56:41.921385Z","end":"2026-03-26T01:56:42.24162Z","steps":["trace[109919598] 'agreement among raft nodes before linearized reading' (duration: 320.162216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.24167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:41.921381Z","time spent":"320.280609ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-03-26T01:56:42.241533Z","caller":"traceutil/trace.go:171","msg":"trace[451325754] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:636; }","duration":"271.485448ms","start":"2026-03-26T01:56:41.970038Z","end":"2026-03-26T01:56:42.241524Z","steps":["trace[451325754] 'agreement among raft nodes before linearized reading' (duration: 271.433737ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.241767Z","caller":"traceutil/trace.go:171","msg":"trace[977084905] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.116300493s","start":"2026-03-26T01:56:41.125458Z","end":"2026-03-26T01:56:42.241759Z","steps":["trace[977084905] 'process raft request' (duration: 1.114180775s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.241824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:41.125445Z","time spent":"1.116349295s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:56:42.241903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118720078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:56:42.241332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.95614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:56:42.241999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.139397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.242013Z","caller":"traceutil/trace.go:171","msg":"trace[1801988302] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:636; }","duration":"228.648209ms","start":"2026-03-26T01:56:42.013349Z","end":"2026-03-26T01:56:42.241997Z","steps":["trace[1801988302] 'agreement among raft nodes before linearized reading' (duration: 227.9582ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.24207Z","caller":"traceutil/trace.go:171","msg":"trace[123900720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"1.118819541s","start":"2026-03-26T01:56:41.123108Z","end":"2026-03-26T01:56:42.241928Z","steps":["trace[123900720] 'agreement among raft nodes before linearized reading' (duration: 1.118724369s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.242106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:41.123093Z","time spent":"1.119004916s","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T01:56:42.242171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.722883ms","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-03-26T01:56:42.242203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.860121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.242208Z","caller":"traceutil/trace.go:171","msg":"trace[1165381988] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:636; }","duration":"210.798315ms","start":"2026-03-26T01:56:42.0314Z","end":"2026-03-26T01:56:42.242199Z","steps":["trace[1165381988] 'agreement among raft nodes before linearized reading' (duration: 210.736864ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.242232Z","caller":"traceutil/trace.go:171","msg":"trace[1587566455] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:636; }","duration":"178.910492ms","start":"2026-03-26T01:56:42.063315Z","end":"2026-03-26T01:56:42.242225Z","steps":["trace[1587566455] 'agreement among raft nodes before linearized reading' (duration: 178.865791ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.241405Z","caller":"traceutil/trace.go:171","msg":"trace[87654742] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:636; }","duration":"227.95698ms","start":"2026-03-26T01:56:42.013433Z","end":"2026-03-26T01:56:42.24139Z","steps":["trace[87654742] 'agreement among raft nodes before linearized reading' (duration: 227.918489ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.242027Z","caller":"traceutil/trace.go:171","msg":"trace[1090971940] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:636; }","duration":"210.188339ms","start":"2026-03-26T01:56:42.031831Z","end":"2026-03-26T01:56:42.242019Z","steps":["trace[1090971940] 'agreement among raft nodes before linearized reading' (duration: 210.147198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.242344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.623831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.242364Z","caller":"traceutil/trace.go:171","msg":"trace[480827489] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:636; }","duration":"179.683983ms","start":"2026-03-26T01:56:42.062674Z","end":"2026-03-26T01:56:42.242358Z","steps":["trace[480827489] 'agreement among raft nodes before linearized reading' (duration: 179.644762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.241433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.136528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:56:42.242456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.93058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:56:42.242449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.203846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.241437Z","caller":"traceutil/trace.go:171","msg":"trace[779594309] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:636; }","duration":"248.522337ms","start":"2026-03-26T01:56:41.992877Z","end":"2026-03-26T01:56:42.241399Z","steps":["trace[779594309] 'agreement among raft nodes before linearized reading' (duration: 248.385183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.241458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.666553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.242498Z","caller":"traceutil/trace.go:171","msg":"trace[1482822816] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:636; }","duration":"211.286878ms","start":"2026-03-26T01:56:42.031201Z","end":"2026-03-26T01:56:42.242487Z","steps":["trace[1482822816] 'agreement among raft nodes before linearized reading' (duration: 211.205456ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.242506Z","caller":"traceutil/trace.go:171","msg":"trace[510815537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:636; }","duration":"248.727942ms","start":"2026-03-26T01:56:41.993769Z","end":"2026-03-26T01:56:42.242497Z","steps":["trace[510815537] 'agreement among raft nodes before linearized reading' (duration: 247.663473ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.24133Z","caller":"traceutil/trace.go:171","msg":"trace[1977654354] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:636; }","duration":"319.975131ms","start":"2026-03-26T01:56:41.921341Z","end":"2026-03-26T01:56:42.241316Z","steps":["trace[1977654354] 'agreement among raft nodes before linearized reading' (duration: 319.838277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.241494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.078253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.242471Z","caller":"traceutil/trace.go:171","msg":"trace[605214277] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:636; }","duration":"272.191787ms","start":"2026-03-26T01:56:41.970269Z","end":"2026-03-26T01:56:42.242461Z","steps":["trace[605214277] 'agreement among raft nodes before linearized reading' (duration: 271.128688ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.242733Z","caller":"traceutil/trace.go:171","msg":"trace[911389509] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:636; }","duration":"321.317487ms","start":"2026-03-26T01:56:41.921402Z","end":"2026-03-26T01:56:42.24272Z","steps":["trace[911389509] 'agreement among raft nodes before linearized reading' (duration: 320.077143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.242696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:41.921325Z","time spent":"321.359028ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"info","ts":"2026-03-26T01:56:42.242474Z","caller":"traceutil/trace.go:171","msg":"trace[407918360] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:636; }","duration":"179.973381ms","start":"2026-03-26T01:56:42.062495Z","end":"2026-03-26T01:56:42.242469Z","steps":["trace[407918360] 'agreement among raft nodes before linearized reading' (duration: 179.94033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.242808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:41.921399Z","time spent":"321.370358ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-03-26T01:56:42.764298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.385808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:56:42.764443Z","caller":"traceutil/trace.go:171","msg":"trace[1571782720] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"202.591243ms","start":"2026-03-26T01:56:42.561824Z","end":"2026-03-26T01:56:42.764415Z","steps":["trace[1571782720] 'read index received' (duration: 23.943488ms)","trace[1571782720] 'applied index is now lower than readState.Index' (duration: 178.646775ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:56:42.764802Z","caller":"traceutil/trace.go:171","msg":"trace[2058312110] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"203.265902ms","start":"2026-03-26T01:56:42.561528Z","end":"2026-03-26T01:56:42.764794Z","steps":["trace[2058312110] 'process raft request' (duration: 24.311168ms)","trace[2058312110] 'compare' (duration: 178.271235ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:56:42.765055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.147828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.765148Z","caller":"traceutil/trace.go:171","msg":"trace[1423089170] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:638; }","duration":"203.321583ms","start":"2026-03-26T01:56:42.561809Z","end":"2026-03-26T01:56:42.765131Z","steps":["trace[1423089170] 'agreement among raft nodes before linearized reading' (duration: 203.068426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.805166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.351393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.805303Z","caller":"traceutil/trace.go:171","msg":"trace[763920788] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:639; }","duration":"217.507617ms","start":"2026-03-26T01:56:42.587731Z","end":"2026-03-26T01:56:42.805238Z","steps":["trace[763920788] 'agreement among raft nodes before linearized reading' (duration: 217.318172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.805533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.810105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.805571Z","caller":"traceutil/trace.go:171","msg":"trace[1848891275] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:639; }","duration":"217.865827ms","start":"2026-03-26T01:56:42.587694Z","end":"2026-03-26T01:56:42.805559Z","steps":["trace[1848891275] 'agreement among raft nodes before linearized reading' (duration: 217.806325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.805718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.066382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.80575Z","caller":"traceutil/trace.go:171","msg":"trace[941574801] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:639; }","duration":"218.122304ms","start":"2026-03-26T01:56:42.587618Z","end":"2026-03-26T01:56:42.80574Z","steps":["trace[941574801] 'agreement among raft nodes before linearized reading' (duration: 218.069022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:42.805913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.049165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:56:42.805947Z","caller":"traceutil/trace.go:171","msg":"trace[1844944592] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:639; }","duration":"244.091346ms","start":"2026-03-26T01:56:42.561846Z","end":"2026-03-26T01:56:42.805937Z","steps":["trace[1844944592] 'agreement among raft nodes before linearized reading' (duration: 244.037935ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:42.806405Z","caller":"traceutil/trace.go:171","msg":"trace[1066641129] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"156.354021ms","start":"2026-03-26T01:56:42.650038Z","end":"2026-03-26T01:56:42.806392Z","steps":["trace[1066641129] 'process raft request' (duration: 152.372174ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:43.99024Z","caller":"traceutil/trace.go:171","msg":"trace[1975337445] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"302.047825ms","start":"2026-03-26T01:56:43.688164Z","end":"2026-03-26T01:56:43.990212Z","steps":["trace[1975337445] 'process raft request' (duration: 301.888821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:43.990378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:43.688152Z","time spent":"302.165198ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:56:46.492166Z","caller":"traceutil/trace.go:171","msg":"trace[14713563] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"232.919274ms","start":"2026-03-26T01:56:46.259211Z","end":"2026-03-26T01:56:46.49213Z","steps":["trace[14713563] 'process raft request' (duration: 232.77567ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:46.704259Z","caller":"traceutil/trace.go:171","msg":"trace[1410843238] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"443.428842ms","start":"2026-03-26T01:56:46.260808Z","end":"2026-03-26T01:56:46.704236Z","steps":["trace[1410843238] 'process raft request' (duration: 443.228897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:46.704401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:46.2608Z","time spent":"443.530645ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:56:46.704498Z","caller":"traceutil/trace.go:171","msg":"trace[505900899] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:730; }","duration":"305.295654ms","start":"2026-03-26T01:56:46.399179Z","end":"2026-03-26T01:56:46.704474Z","steps":["trace[505900899] 'read index received' (duration: 92.820633ms)","trace[505900899] 'applied index is now lower than readState.Index' (duration: 212.47346ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:56:46.70462Z","caller":"traceutil/trace.go:171","msg":"trace[863851206] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"443.602357ms","start":"2026-03-26T01:56:46.260967Z","end":"2026-03-26T01:56:46.704569Z","steps":["trace[863851206] 'process raft request' (duration: 443.232837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:46.704755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.56129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-03-26T01:56:46.704775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:46.260962Z","time spent":"443.736141ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:56:46.704817Z","caller":"traceutil/trace.go:171","msg":"trace[1651645408] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:708; }","duration":"305.637692ms","start":"2026-03-26T01:56:46.399166Z","end":"2026-03-26T01:56:46.704804Z","steps":["trace[1651645408] 'agreement among raft nodes before linearized reading' (duration: 305.455497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:46.704855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:46.399112Z","time spent":"305.735015ms","remote":"127.0.0.1:40452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-03-26T01:56:46.70614Z","caller":"traceutil/trace.go:171","msg":"trace[1625356282] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"119.32392ms","start":"2026-03-26T01:56:46.586793Z","end":"2026-03-26T01:56:46.706117Z","steps":["trace[1625356282] 'process raft request' (duration: 119.213287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:46.706294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.186089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-03-26T01:56:46.706362Z","caller":"traceutil/trace.go:171","msg":"trace[1012588884] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:709; }","duration":"108.315962ms","start":"2026-03-26T01:56:46.59803Z","end":"2026-03-26T01:56:46.706346Z","steps":["trace[1012588884] 'agreement among raft nodes before linearized reading' (duration: 108.077236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:46.706619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.998711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ckv2k\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-03-26T01:56:46.706733Z","caller":"traceutil/trace.go:171","msg":"trace[1011615547] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ckv2k; range_end:; response_count:1; response_revision:709; }","duration":"119.493724ms","start":"2026-03-26T01:56:46.58722Z","end":"2026-03-26T01:56:46.706713Z","steps":["trace[1011615547] 'agreement among raft nodes before linearized reading' (duration: 118.888848ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:56.957777Z","caller":"traceutil/trace.go:171","msg":"trace[415408554] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"205.384038ms","start":"2026-03-26T01:56:56.752371Z","end":"2026-03-26T01:56:56.957755Z","steps":["trace[415408554] 'process raft request' (duration: 205.247905ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:56.975312Z","caller":"traceutil/trace.go:171","msg":"trace[549024738] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"221.653949ms","start":"2026-03-26T01:56:56.753639Z","end":"2026-03-26T01:56:56.975293Z","steps":["trace[549024738] 'process raft request' (duration: 221.534526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:58.904135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.260143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cfjkd\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-03-26T01:56:58.904161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.569118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T01:56:58.904221Z","caller":"traceutil/trace.go:171","msg":"trace[1821338736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"336.671031ms","start":"2026-03-26T01:56:58.567533Z","end":"2026-03-26T01:56:58.904204Z","steps":["trace[1821338736] 'range keys from in-memory index tree' (duration: 336.410544ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:58.90422Z","caller":"traceutil/trace.go:171","msg":"trace[911594389] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cfjkd; range_end:; response_count:1; response_revision:798; }","duration":"257.397137ms","start":"2026-03-26T01:56:58.646803Z","end":"2026-03-26T01:56:58.904201Z","steps":["trace[911594389] 'range keys from in-memory index tree' (duration: 257.022197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:58.90426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:58.567514Z","time spent":"336.737493ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T01:56:59.226871Z","caller":"traceutil/trace.go:171","msg":"trace[160316488] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"318.748905ms","start":"2026-03-26T01:56:58.908103Z","end":"2026-03-26T01:56:59.226852Z","steps":["trace[160316488] 'process raft request' (duration: 318.629091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:59.227033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:58.908081Z","time spent":"318.883279ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:56:59.237184Z","caller":"traceutil/trace.go:171","msg":"trace[408768511] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"275.590254ms","start":"2026-03-26T01:56:58.961565Z","end":"2026-03-26T01:56:59.237156Z","steps":["trace[408768511] 'read index received' (duration: 265.142053ms)","trace[408768511] 'applied index is now lower than readState.Index' (duration: 10.447201ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:56:59.237228Z","caller":"traceutil/trace.go:171","msg":"trace[974202472] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"324.950149ms","start":"2026-03-26T01:56:58.912253Z","end":"2026-03-26T01:56:59.237203Z","steps":["trace[974202472] 'process raft request' (duration: 324.718282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:56:59.23732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:56:58.912235Z","time spent":"325.041872ms","remote":"127.0.0.1:40606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:56:59.237591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.009157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-26T01:56:59.237591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.115852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:56:59.237642Z","caller":"traceutil/trace.go:171","msg":"trace[1405872803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"276.11695ms","start":"2026-03-26T01:56:58.961513Z","end":"2026-03-26T01:56:59.23763Z","steps":["trace[1405872803] 'agreement among raft nodes before linearized reading' (duration: 275.964905ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:56:59.237684Z","caller":"traceutil/trace.go:171","msg":"trace[1023505639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"258.246107ms","start":"2026-03-26T01:56:58.979414Z","end":"2026-03-26T01:56:59.23766Z","steps":["trace[1023505639] 'agreement among raft nodes before linearized reading' (duration: 258.02785ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:02.878752Z","caller":"traceutil/trace.go:171","msg":"trace[1943793231] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"117.404731ms","start":"2026-03-26T01:57:02.761323Z","end":"2026-03-26T01:57:02.878727Z","steps":["trace[1943793231] 'process raft request' (duration: 117.257217ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:03.187972Z","caller":"traceutil/trace.go:171","msg":"trace[1600107784] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"244.943604ms","start":"2026-03-26T01:57:02.943002Z","end":"2026-03-26T01:57:03.187945Z","steps":["trace[1600107784] 'process raft request' (duration: 242.135815ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:03.539254Z","caller":"traceutil/trace.go:171","msg":"trace[1087805416] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"300.585733ms","start":"2026-03-26T01:57:03.238642Z","end":"2026-03-26T01:57:03.539228Z","steps":["trace[1087805416] 'read index received' (duration: 300.431928ms)","trace[1087805416] 'applied index is now lower than readState.Index' (duration: 152.085µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:57:03.539277Z","caller":"traceutil/trace.go:171","msg":"trace[1813687264] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"346.662719ms","start":"2026-03-26T01:57:03.192587Z","end":"2026-03-26T01:57:03.53925Z","steps":["trace[1813687264] 'process raft request' (duration: 346.518375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:03.539507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.886792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-26T01:57:03.539522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.492499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-26T01:57:03.539562Z","caller":"traceutil/trace.go:171","msg":"trace[597285682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:819; }","duration":"300.971825ms","start":"2026-03-26T01:57:03.238575Z","end":"2026-03-26T01:57:03.539547Z","steps":["trace[597285682] 'agreement among raft nodes before linearized reading' (duration: 300.780449ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:03.539575Z","caller":"traceutil/trace.go:171","msg":"trace[629119461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:819; }","duration":"281.576981ms","start":"2026-03-26T01:57:03.257976Z","end":"2026-03-26T01:57:03.539553Z","steps":["trace[629119461] 'agreement among raft nodes before linearized reading' (duration: 281.395055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:03.539599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:03.238563Z","time spent":"301.028976ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-26T01:57:03.53978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.858517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T01:57:03.53982Z","caller":"traceutil/trace.go:171","msg":"trace[657647900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"186.925499ms","start":"2026-03-26T01:57:03.352886Z","end":"2026-03-26T01:57:03.539811Z","steps":["trace[657647900] 'agreement among raft nodes before linearized reading' (duration: 186.826336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:03.539967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:03.192569Z","time spent":"346.878105ms","remote":"127.0.0.1:40510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1535,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T01:57:03.540113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.542132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T01:57:03.540273Z","caller":"traceutil/trace.go:171","msg":"trace[1064108481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:819; }","duration":"276.742809ms","start":"2026-03-26T01:57:03.26351Z","end":"2026-03-26T01:57:03.540252Z","steps":["trace[1064108481] 'agreement among raft nodes before linearized reading' (duration: 276.295475ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:03.835983Z","caller":"traceutil/trace.go:171","msg":"trace[1333702804] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"194.059915ms","start":"2026-03-26T01:57:03.641907Z","end":"2026-03-26T01:57:03.835967Z","steps":["trace[1333702804] 'process raft request' (duration: 193.972162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:04.226249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.061282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:57:04.226404Z","caller":"traceutil/trace.go:171","msg":"trace[1557021617] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"387.107817ms","start":"2026-03-26T01:57:03.839267Z","end":"2026-03-26T01:57:04.226375Z","steps":["trace[1557021617] 'process raft request' (duration: 57.855859ms)","trace[1557021617] 'compare' (duration: 328.960489ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:57:04.226535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:03.839246Z","time spent":"387.225682ms","remote":"127.0.0.1:40730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1131,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:57:04.226971Z","caller":"traceutil/trace.go:171","msg":"trace[162207848] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"387.131108ms","start":"2026-03-26T01:57:03.839805Z","end":"2026-03-26T01:57:04.226936Z","steps":["trace[162207848] 'process raft request' (duration: 386.533259ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:04.227199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:03.839797Z","time spent":"387.292673ms","remote":"127.0.0.1:40730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1096,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T01:57:04.344561Z","caller":"traceutil/trace.go:171","msg":"trace[503051688] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"111.638509ms","start":"2026-03-26T01:57:04.232898Z","end":"2026-03-26T01:57:04.344537Z","steps":["trace[503051688] 'process raft request' (duration: 93.596049ms)","trace[503051688] 'compare' (duration: 17.885595ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:57:04.737804Z","caller":"traceutil/trace.go:171","msg":"trace[26799246] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"181.435526ms","start":"2026-03-26T01:57:04.556338Z","end":"2026-03-26T01:57:04.737773Z","steps":["trace[26799246] 'process raft request' (duration: 87.184787ms)","trace[26799246] 'compare' (duration: 93.9589ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:57:05.022735Z","caller":"traceutil/trace.go:171","msg":"trace[1909846786] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"302.205544ms","start":"2026-03-26T01:57:04.720504Z","end":"2026-03-26T01:57:05.02271Z","steps":["trace[1909846786] 'read index received' (duration: 302.050979ms)","trace[1909846786] 'applied index is now lower than readState.Index' (duration: 153.475µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:57:05.022826Z","caller":"traceutil/trace.go:171","msg":"trace[1156896864] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"449.537552ms","start":"2026-03-26T01:57:04.573257Z","end":"2026-03-26T01:57:05.022794Z","steps":["trace[1156896864] 'process raft request' (duration: 449.244802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:05.023007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:04.573246Z","time spent":"449.673256ms","remote":"127.0.0.1:40834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5601,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:57:05.023048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.515444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-26T01:57:05.023091Z","caller":"traceutil/trace.go:171","msg":"trace[2035733501] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:848; }","duration":"302.603297ms","start":"2026-03-26T01:57:04.720473Z","end":"2026-03-26T01:57:05.023076Z","steps":["trace[2035733501] 'agreement among raft nodes before linearized reading' (duration: 302.367529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:05.023125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:04.720461Z","time spent":"302.656428ms","remote":"127.0.0.1:40628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":869,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-03-26T01:57:05.289634Z","caller":"traceutil/trace.go:171","msg":"trace[773385394] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"266.793064ms","start":"2026-03-26T01:57:05.022815Z","end":"2026-03-26T01:57:05.289608Z","steps":["trace[773385394] 'read index received' (duration: 264.215283ms)","trace[773385394] 'applied index is now lower than readState.Index' (duration: 2.576911ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:57:05.289702Z","caller":"traceutil/trace.go:171","msg":"trace[2062736230] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"408.822174ms","start":"2026-03-26T01:57:04.880841Z","end":"2026-03-26T01:57:05.289663Z","steps":["trace[2062736230] 'process raft request' (duration: 406.991486ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:05.289966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.561929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-26T01:57:05.290047Z","caller":"traceutil/trace.go:171","msg":"trace[804231542] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:849; }","duration":"478.689423ms","start":"2026-03-26T01:57:04.811337Z","end":"2026-03-26T01:57:05.290027Z","steps":["trace[804231542] 'agreement among raft nodes before linearized reading' (duration: 478.436985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:05.2901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:04.811321Z","time spent":"478.770486ms","remote":"127.0.0.1:40436","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.140\" "} {"level":"warn","ts":"2026-03-26T01:57:05.289777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.851209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:57:05.290191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:04.880813Z","time spent":"409.210386ms","remote":"127.0.0.1:40506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036298,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:57:05.290209Z","caller":"traceutil/trace.go:171","msg":"trace[1344895471] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:849; }","duration":"380.320713ms","start":"2026-03-26T01:57:04.909855Z","end":"2026-03-26T01:57:05.290176Z","steps":["trace[1344895471] 'agreement among raft nodes before linearized reading' (duration: 379.852998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:05.290166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.925238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:57:05.290279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:04.909834Z","time spent":"380.425157ms","remote":"127.0.0.1:40574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-03-26T01:57:05.290334Z","caller":"traceutil/trace.go:171","msg":"trace[1407284321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"149.132714ms","start":"2026-03-26T01:57:05.141175Z","end":"2026-03-26T01:57:05.290308Z","steps":["trace[1407284321] 'agreement among raft nodes before linearized reading' (duration: 148.865306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:05.29047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.672049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-fr5f2\" ","response":"range_response_count:1 size:5393"} {"level":"info","ts":"2026-03-26T01:57:05.290554Z","caller":"traceutil/trace.go:171","msg":"trace[908566809] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-fr5f2; range_end:; response_count:1; response_revision:849; }","duration":"265.803563ms","start":"2026-03-26T01:57:05.024734Z","end":"2026-03-26T01:57:05.290538Z","steps":["trace[908566809] 'agreement among raft nodes before linearized reading' (duration: 265.271176ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:27.9529Z","caller":"traceutil/trace.go:171","msg":"trace[1144450821] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"152.368667ms","start":"2026-03-26T01:57:27.800513Z","end":"2026-03-26T01:57:27.952882Z","steps":["trace[1144450821] 'process raft request' (duration: 152.14847ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:27.952964Z","caller":"traceutil/trace.go:171","msg":"trace[1911856135] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"109.75549ms","start":"2026-03-26T01:57:27.843056Z","end":"2026-03-26T01:57:27.952811Z","steps":["trace[1911856135] 'read index received' (duration: 109.618926ms)","trace[1911856135] 'applied index is now lower than readState.Index' (duration: 135.904µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:57:27.953098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.038499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T01:57:27.953122Z","caller":"traceutil/trace.go:171","msg":"trace[221512731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"110.115372ms","start":"2026-03-26T01:57:27.843Z","end":"2026-03-26T01:57:27.953115Z","steps":["trace[221512731] 'agreement among raft nodes before linearized reading' (duration: 110.020289ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:28.479193Z","caller":"traceutil/trace.go:171","msg":"trace[1781871444] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"149.545597ms","start":"2026-03-26T01:57:28.329628Z","end":"2026-03-26T01:57:28.479173Z","steps":["trace[1781871444] 'process raft request' (duration: 149.428863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:36.541134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604711624872550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T01:57:37.041645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604711624872550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T01:57:37.067718Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.081270602s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T01:57:37.068039Z","caller":"traceutil/trace.go:171","msg":"trace[2004806284] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.081694916s","start":"2026-03-26T01:57:35.986326Z","end":"2026-03-26T01:57:37.068021Z","steps":["trace[2004806284] 'process raft request' (duration: 1.081583552s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:37.068169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:35.986301Z","time spent":"1.081793119s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:57:37.346944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.499667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:57:37.347048Z","caller":"traceutil/trace.go:171","msg":"trace[1374761057] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"1.306264745s","start":"2026-03-26T01:57:36.040767Z","end":"2026-03-26T01:57:37.347032Z","steps":["trace[1374761057] 'read index received' (duration: 1.027254365s)","trace[1374761057] 'applied index is now lower than readState.Index' (duration: 279.0086ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:57:37.347085Z","caller":"traceutil/trace.go:171","msg":"trace[1439109241] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"843.470375ms","start":"2026-03-26T01:57:36.503585Z","end":"2026-03-26T01:57:37.347055Z","steps":["trace[1439109241] 'process raft request' (duration: 643.742661ms)","trace[1439109241] 'compare' (duration: 199.091004ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:57:37.347196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.093252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:57:37.347225Z","caller":"traceutil/trace.go:171","msg":"trace[1106665268] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1017; }","duration":"824.163674ms","start":"2026-03-26T01:57:36.523053Z","end":"2026-03-26T01:57:37.347217Z","steps":["trace[1106665268] 'agreement among raft nodes before linearized reading' (duration: 824.104852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:37.347224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:36.50356Z","time spent":"843.581097ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:57:37.347228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306461281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-26T01:57:37.347259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:36.523033Z","time spent":"824.217016ms","remote":"127.0.0.1:40766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-03-26T01:57:37.347267Z","caller":"traceutil/trace.go:171","msg":"trace[2112129353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"1.306541993s","start":"2026-03-26T01:57:36.040716Z","end":"2026-03-26T01:57:37.347258Z","steps":["trace[2112129353] 'agreement among raft nodes before linearized reading' (duration: 1.306405739s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:37.347262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29155747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-26T01:57:37.347295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:36.040694Z","time spent":"1.306595295s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T01:57:37.347324Z","caller":"traceutil/trace.go:171","msg":"trace[830491555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.291660493s","start":"2026-03-26T01:57:36.055652Z","end":"2026-03-26T01:57:37.347312Z","steps":["trace[830491555] 'agreement among raft nodes before linearized reading' (duration: 1.291504018s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:37.347354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:36.055633Z","time spent":"1.291715015s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T01:57:37.347443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206941825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:57:37.347464Z","caller":"traceutil/trace.go:171","msg":"trace[201381911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"1.206998336s","start":"2026-03-26T01:57:36.14046Z","end":"2026-03-26T01:57:37.347458Z","steps":["trace[201381911] 'agreement among raft nodes before linearized reading' (duration: 1.206967956s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:37.34748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:36.140435Z","time spent":"1.207041128s","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T01:57:37.347507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29125233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T01:57:37.347535Z","caller":"traceutil/trace.go:171","msg":"trace[922482686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.291293012s","start":"2026-03-26T01:57:36.056235Z","end":"2026-03-26T01:57:37.347528Z","steps":["trace[922482686] 'agreement among raft nodes before linearized reading' (duration: 1.29122894s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:37.347558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:36.056229Z","time spent":"1.291325423s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T01:57:37.347739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.587457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-26T01:57:37.347767Z","caller":"traceutil/trace.go:171","msg":"trace[2076849190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"718.669359ms","start":"2026-03-26T01:57:36.629091Z","end":"2026-03-26T01:57:37.34776Z","steps":["trace[2076849190] 'agreement among raft nodes before linearized reading' (duration: 718.271596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:37.347788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:36.629072Z","time spent":"718.71054ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-26T01:57:39.36446Z","caller":"traceutil/trace.go:171","msg":"trace[1668405048] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"222.957028ms","start":"2026-03-26T01:57:39.141481Z","end":"2026-03-26T01:57:39.364438Z","steps":["trace[1668405048] 'read index received' (duration: 222.824484ms)","trace[1668405048] 'applied index is now lower than readState.Index' (duration: 131.334µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:57:39.364519Z","caller":"traceutil/trace.go:171","msg":"trace[1633596147] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"287.975134ms","start":"2026-03-26T01:57:39.076515Z","end":"2026-03-26T01:57:39.36449Z","steps":["trace[1633596147] 'process raft request' (duration: 287.740796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:39.364583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.079133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:57:39.364631Z","caller":"traceutil/trace.go:171","msg":"trace[1836619898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"223.175695ms","start":"2026-03-26T01:57:39.141446Z","end":"2026-03-26T01:57:39.364621Z","steps":["trace[1836619898] 'agreement among raft nodes before linearized reading' (duration: 223.086912ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:51.827347Z","caller":"traceutil/trace.go:171","msg":"trace[482445101] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"126.002525ms","start":"2026-03-26T01:57:51.70131Z","end":"2026-03-26T01:57:51.827313Z","steps":["trace[482445101] 'process raft request' (duration: 125.870731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:56.258717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555604711624872705,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T01:57:56.586233Z","caller":"traceutil/trace.go:171","msg":"trace[1757209383] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1120; }","duration":"828.460283ms","start":"2026-03-26T01:57:55.757714Z","end":"2026-03-26T01:57:56.586174Z","steps":["trace[1757209383] 'read index received' (duration: 828.030121ms)","trace[1757209383] 'applied index is now lower than readState.Index' (duration: 429.242µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:57:56.586492Z","caller":"traceutil/trace.go:171","msg":"trace[780007726] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"870.427233ms","start":"2026-03-26T01:57:55.716054Z","end":"2026-03-26T01:57:56.586481Z","steps":["trace[780007726] 'process raft request' (duration: 869.976831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:56.586592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:55.716034Z","time spent":"870.480925ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:57:56.586757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.042788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T01:57:56.58678Z","caller":"traceutil/trace.go:171","msg":"trace[1431435997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1078; }","duration":"829.09247ms","start":"2026-03-26T01:57:55.757683Z","end":"2026-03-26T01:57:56.586775Z","steps":["trace[1431435997] 'agreement among raft nodes before linearized reading' (duration: 828.999687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:56.586796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:55.757666Z","time spent":"829.127602ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T01:57:56.587011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.292268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:57:56.587033Z","caller":"traceutil/trace.go:171","msg":"trace[1414792620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"445.34688ms","start":"2026-03-26T01:57:56.14168Z","end":"2026-03-26T01:57:56.587027Z","steps":["trace[1414792620] 'agreement among raft nodes before linearized reading' (duration: 445.308969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:56.587048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:56.141659Z","time spent":"445.384191ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T01:57:56.587042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.260305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-26T01:57:56.587105Z","caller":"traceutil/trace.go:171","msg":"trace[1077498186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"829.363868ms","start":"2026-03-26T01:57:55.757727Z","end":"2026-03-26T01:57:56.587091Z","steps":["trace[1077498186] 'agreement among raft nodes before linearized reading' (duration: 829.124101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:56.587142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:55.757706Z","time spent":"829.429009ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T01:57:56.587301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.127929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-26T01:57:56.587322Z","caller":"traceutil/trace.go:171","msg":"trace[697615532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1078; }","duration":"617.183511ms","start":"2026-03-26T01:57:55.970133Z","end":"2026-03-26T01:57:56.587316Z","steps":["trace[697615532] 'agreement among raft nodes before linearized reading' (duration: 617.130119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:56.587336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:55.970113Z","time spent":"617.219412ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T01:57:56.978613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.23834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:57:56.978698Z","caller":"traceutil/trace.go:171","msg":"trace[298660903] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"387.531665ms","start":"2026-03-26T01:57:56.591154Z","end":"2026-03-26T01:57:56.978685Z","steps":["trace[298660903] 'process raft request' (duration: 187.154171ms)","trace[298660903] 'compare' (duration: 199.943422ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:57:56.978751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:56.591138Z","time spent":"387.594496ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:57:57.120746Z","caller":"traceutil/trace.go:171","msg":"trace[751769288] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"528.574382ms","start":"2026-03-26T01:57:56.592116Z","end":"2026-03-26T01:57:57.120691Z","steps":["trace[751769288] 'process raft request' (duration: 507.879095ms)","trace[751769288] 'compare' (duration: 20.385299ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:57:57.120723Z","caller":"traceutil/trace.go:171","msg":"trace[221521242] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1122; }","duration":"319.765327ms","start":"2026-03-26T01:57:56.800902Z","end":"2026-03-26T01:57:57.120667Z","steps":["trace[221521242] 'read index received' (duration: 299.07498ms)","trace[221521242] 'applied index is now lower than readState.Index' (duration: 20.688607ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:57:57.120893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:56.592093Z","time spent":"528.723176ms","remote":"127.0.0.1:40684","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-03-26T01:57:57.120964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.027934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T01:57:57.121008Z","caller":"traceutil/trace.go:171","msg":"trace[649782234] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"527.446411ms","start":"2026-03-26T01:57:56.593554Z","end":"2026-03-26T01:57:57.121Z","steps":["trace[649782234] 'process raft request' (duration: 527.038729ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:57:57.121014Z","caller":"traceutil/trace.go:171","msg":"trace[1983425771] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1081; }","duration":"320.153578ms","start":"2026-03-26T01:57:56.800844Z","end":"2026-03-26T01:57:57.120998Z","steps":["trace[1983425771] 'agreement among raft nodes before linearized reading' (duration: 320.021254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:57:57.121044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:56.593545Z","time spent":"527.478221ms","remote":"127.0.0.1:40684","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-03-26T01:57:57.121067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:57:56.800823Z","time spent":"320.23058ms","remote":"127.0.0.1:40686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-03-26T01:57:58.830057Z","caller":"traceutil/trace.go:171","msg":"trace[210796373] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"235.24408ms","start":"2026-03-26T01:57:58.594784Z","end":"2026-03-26T01:57:58.830028Z","steps":["trace[210796373] 'process raft request' (duration: 235.057525ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:03.460494Z","caller":"traceutil/trace.go:171","msg":"trace[217190283] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"614.536587ms","start":"2026-03-26T01:58:02.845936Z","end":"2026-03-26T01:58:03.460473Z","steps":["trace[217190283] 'process raft request' (duration: 614.361683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:03.460628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.413717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:03.460733Z","caller":"traceutil/trace.go:171","msg":"trace[612416740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1097; }","duration":"319.51242ms","start":"2026-03-26T01:58:03.14116Z","end":"2026-03-26T01:58:03.460672Z","steps":["trace[612416740] 'agreement among raft nodes before linearized reading' (duration: 319.404867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:03.460807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:02.845918Z","time spent":"614.635421ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:58:03.460838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:03.141142Z","time spent":"319.684745ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T01:58:03.461006Z","caller":"traceutil/trace.go:171","msg":"trace[368313055] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"319.243613ms","start":"2026-03-26T01:58:03.141199Z","end":"2026-03-26T01:58:03.460442Z","steps":["trace[368313055] 'read index received' (duration: 319.068838ms)","trace[368313055] 'applied index is now lower than readState.Index' (duration: 173.955µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:03.4613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.463495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T01:58:03.461326Z","caller":"traceutil/trace.go:171","msg":"trace[250865033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1097; }","duration":"220.532037ms","start":"2026-03-26T01:58:03.240786Z","end":"2026-03-26T01:58:03.461318Z","steps":["trace[250865033] 'agreement among raft nodes before linearized reading' (duration: 220.446084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:03.461376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.879118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-26T01:58:03.461376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.211973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-26T01:58:03.46145Z","caller":"traceutil/trace.go:171","msg":"trace[1047048867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1097; }","duration":"254.986571ms","start":"2026-03-26T01:58:03.206442Z","end":"2026-03-26T01:58:03.461429Z","steps":["trace[1047048867] 'agreement among raft nodes before linearized reading' (duration: 254.809906ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:03.46148Z","caller":"traceutil/trace.go:171","msg":"trace[1779056053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1097; }","duration":"299.367528ms","start":"2026-03-26T01:58:03.162096Z","end":"2026-03-26T01:58:03.461463Z","steps":["trace[1779056053] 'agreement among raft nodes before linearized reading' (duration: 299.127511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:03.462365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:03.162075Z","time spent":"300.268633ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-26T01:58:03.867673Z","caller":"traceutil/trace.go:171","msg":"trace[202695007] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1141; }","duration":"285.528848ms","start":"2026-03-26T01:58:03.582127Z","end":"2026-03-26T01:58:03.867656Z","steps":["trace[202695007] 'read index received' (duration: 275.132303ms)","trace[202695007] 'applied index is now lower than readState.Index' (duration: 10.395985ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:03.867871Z","caller":"traceutil/trace.go:171","msg":"trace[1692278905] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"402.084084ms","start":"2026-03-26T01:58:03.465778Z","end":"2026-03-26T01:58:03.867862Z","steps":["trace[1692278905] 'process raft request' (duration: 391.398021ms)","trace[1692278905] 'compare' (duration: 10.003004ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:03.867956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:03.465763Z","time spent":"402.137665ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:58:03.868144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.993708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-26T01:58:03.868165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.034142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:58:03.868178Z","caller":"traceutil/trace.go:171","msg":"trace[338513080] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1101; }","duration":"229.043639ms","start":"2026-03-26T01:58:03.639126Z","end":"2026-03-26T01:58:03.868169Z","steps":["trace[338513080] 'agreement among raft nodes before linearized reading' (duration: 228.975487ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:03.868196Z","caller":"traceutil/trace.go:171","msg":"trace[1788804066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1101; }","duration":"286.103593ms","start":"2026-03-26T01:58:03.582082Z","end":"2026-03-26T01:58:03.868186Z","steps":["trace[1788804066] 'agreement among raft nodes before linearized reading' (duration: 285.98804ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:03.868335Z","caller":"traceutil/trace.go:171","msg":"trace[156187582] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"402.438823ms","start":"2026-03-26T01:58:03.465887Z","end":"2026-03-26T01:58:03.868326Z","steps":["trace[156187582] 'process raft request' (duration: 401.687613ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:03.868385Z","caller":"traceutil/trace.go:171","msg":"trace[533634217] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"241.84135ms","start":"2026-03-26T01:58:03.626536Z","end":"2026-03-26T01:58:03.868378Z","steps":["trace[533634217] 'process raft request' (duration: 241.097599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:03.86838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:03.465863Z","time spent":"402.493815ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:58:03.868399Z","caller":"traceutil/trace.go:171","msg":"trace[981608985] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"402.357951ms","start":"2026-03-26T01:58:03.466019Z","end":"2026-03-26T01:58:03.868377Z","steps":["trace[981608985] 'process raft request' (duration: 401.59073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:03.868635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:03.466011Z","time spent":"402.534776ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:58:03.993224Z","caller":"traceutil/trace.go:171","msg":"trace[1498868290] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"121.140681ms","start":"2026-03-26T01:58:03.87206Z","end":"2026-03-26T01:58:03.9932Z","steps":["trace[1498868290] 'process raft request' (duration: 120.994467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:05.869956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.109772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:05.870198Z","caller":"traceutil/trace.go:171","msg":"trace[416783291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"728.39346ms","start":"2026-03-26T01:58:05.141758Z","end":"2026-03-26T01:58:05.870151Z","steps":["trace[416783291] 'range keys from in-memory index tree' (duration: 727.992528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:05.870293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:05.141734Z","time spent":"728.546363ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T01:58:05.871925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.773854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T01:58:05.872031Z","caller":"traceutil/trace.go:171","msg":"trace[542785547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"406.923199ms","start":"2026-03-26T01:58:05.465094Z","end":"2026-03-26T01:58:05.872018Z","steps":["trace[542785547] 'range keys from in-memory index tree' (duration: 406.495797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:05.872112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:05.465065Z","time spent":"407.036261ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T01:58:05.873877Z","caller":"traceutil/trace.go:171","msg":"trace[1513768562] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"251.582111ms","start":"2026-03-26T01:58:05.622275Z","end":"2026-03-26T01:58:05.873857Z","steps":["trace[1513768562] 'process raft request' (duration: 251.119259ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:06.202111Z","caller":"traceutil/trace.go:171","msg":"trace[444838741] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"325.07554ms","start":"2026-03-26T01:58:05.876997Z","end":"2026-03-26T01:58:06.202073Z","steps":["trace[444838741] 'read index received' (duration: 225.224967ms)","trace[444838741] 'applied index is now lower than readState.Index' (duration: 99.848983ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:06.20215Z","caller":"traceutil/trace.go:171","msg":"trace[1322401316] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"325.303466ms","start":"2026-03-26T01:58:05.876821Z","end":"2026-03-26T01:58:06.202125Z","steps":["trace[1322401316] 'process raft request' (duration: 225.386751ms)","trace[1322401316] 'compare' (duration: 99.620397ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:06.202294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:05.876804Z","time spent":"325.405489ms","remote":"127.0.0.1:40684","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-03-26T01:58:06.202589Z","caller":"traceutil/trace.go:171","msg":"trace[642060708] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"323.25308ms","start":"2026-03-26T01:58:05.879306Z","end":"2026-03-26T01:58:06.202559Z","steps":["trace[642060708] 'process raft request' (duration: 322.691365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:06.202861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:05.879294Z","time spent":"323.429574ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:58:06.202935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.763499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-26T01:58:06.202984Z","caller":"traceutil/trace.go:171","msg":"trace[1874932732] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"323.505837ms","start":"2026-03-26T01:58:05.879459Z","end":"2026-03-26T01:58:06.202965Z","steps":["trace[1874932732] 'process raft request' (duration: 322.976293ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:06.203007Z","caller":"traceutil/trace.go:171","msg":"trace[523248848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1107; }","duration":"325.912733ms","start":"2026-03-26T01:58:05.877077Z","end":"2026-03-26T01:58:06.20299Z","steps":["trace[523248848] 'agreement among raft nodes before linearized reading' (duration: 325.668616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:06.203055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:05.877037Z","time spent":"326.009835ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T01:58:06.203084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:05.879448Z","time spent":"323.584319ms","remote":"127.0.0.1:40684","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-03-26T01:58:06.20333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.191467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:58:06.203395Z","caller":"traceutil/trace.go:171","msg":"trace[1884793894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1107; }","duration":"205.288819ms","start":"2026-03-26T01:58:05.99809Z","end":"2026-03-26T01:58:06.203379Z","steps":["trace[1884793894] 'agreement among raft nodes before linearized reading' (duration: 205.111205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:06.203382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.365525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-26T01:58:06.203462Z","caller":"traceutil/trace.go:171","msg":"trace[1654337845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1107; }","duration":"326.466708ms","start":"2026-03-26T01:58:05.876976Z","end":"2026-03-26T01:58:06.203443Z","steps":["trace[1654337845] 'agreement among raft nodes before linearized reading' (duration: 325.268815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:06.203506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:05.876965Z","time spent":"326.52907ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T01:58:06.952842Z","caller":"traceutil/trace.go:171","msg":"trace[657608128] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"745.762735ms","start":"2026-03-26T01:58:06.20705Z","end":"2026-03-26T01:58:06.952812Z","steps":["trace[657608128] 'process raft request' (duration: 745.535529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:06.953009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:06.207037Z","time spent":"745.889329ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:58:07.00725Z","caller":"traceutil/trace.go:171","msg":"trace[1192290922] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1153; }","duration":"373.205092ms","start":"2026-03-26T01:58:06.634027Z","end":"2026-03-26T01:58:07.007232Z","steps":["trace[1192290922] 'read index received' (duration: 318.820818ms)","trace[1192290922] 'applied index is now lower than readState.Index' (duration: 54.383514ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:07.007313Z","caller":"traceutil/trace.go:171","msg":"trace[1513289424] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"799.225784ms","start":"2026-03-26T01:58:06.208058Z","end":"2026-03-26T01:58:07.007283Z","steps":["trace[1513289424] 'process raft request' (duration: 758.444114ms)","trace[1513289424] 'compare' (duration: 40.588405ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:07.007367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.325626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:07.007394Z","caller":"traceutil/trace.go:171","msg":"trace[1679919697] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1110; }","duration":"373.410778ms","start":"2026-03-26T01:58:06.633976Z","end":"2026-03-26T01:58:07.007387Z","steps":["trace[1679919697] 'agreement among raft nodes before linearized reading' (duration: 373.330296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:07.007454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:06.208046Z","time spent":"799.339107ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:58:07.007531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:06.633956Z","time spent":"373.464169ms","remote":"127.0.0.1:60598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-26T01:58:07.007662Z","caller":"traceutil/trace.go:171","msg":"trace[762584246] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"799.591764ms","start":"2026-03-26T01:58:06.208055Z","end":"2026-03-26T01:58:07.007647Z","steps":["trace[762584246] 'process raft request' (duration: 799.141462ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:07.007758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:06.208038Z","time spent":"799.653645ms","remote":"127.0.0.1:40684","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-03-26T01:58:08.616079Z","caller":"traceutil/trace.go:171","msg":"trace[1232020300] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"404.598635ms","start":"2026-03-26T01:58:08.211457Z","end":"2026-03-26T01:58:08.616056Z","steps":["trace[1232020300] 'process raft request' (duration: 404.382209ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:08.616229Z","caller":"traceutil/trace.go:171","msg":"trace[416433400] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1156; }","duration":"193.65028ms","start":"2026-03-26T01:58:08.422552Z","end":"2026-03-26T01:58:08.616202Z","steps":["trace[416433400] 'read index received' (duration: 193.234058ms)","trace[416433400] 'applied index is now lower than readState.Index' (duration: 413.932µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:08.616334Z","caller":"traceutil/trace.go:171","msg":"trace[1506549550] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"404.78672ms","start":"2026-03-26T01:58:08.211523Z","end":"2026-03-26T01:58:08.616309Z","steps":["trace[1506549550] 'process raft request' (duration: 404.492312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:08.616415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.802634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:58:08.616468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:08.211501Z","time spent":"404.897602ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:58:08.616452Z","caller":"traceutil/trace.go:171","msg":"trace[656614320] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1113; }","duration":"193.926737ms","start":"2026-03-26T01:58:08.422514Z","end":"2026-03-26T01:58:08.616441Z","steps":["trace[656614320] 'agreement among raft nodes before linearized reading' (duration: 193.789044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:08.616678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:08.211439Z","time spent":"404.786199ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:58:09.431317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.120993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T01:58:09.431429Z","caller":"traceutil/trace.go:171","msg":"trace[972960585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"419.232697ms","start":"2026-03-26T01:58:09.012142Z","end":"2026-03-26T01:58:09.431375Z","steps":["trace[972960585] 'range keys from in-memory index tree' (duration: 418.956949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:09.431477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:09.012125Z","time spent":"419.341229ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T01:58:09.431691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.850356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-26T01:58:09.431678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.347273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:09.431725Z","caller":"traceutil/trace.go:171","msg":"trace[1649657011] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1113; }","duration":"240.921879ms","start":"2026-03-26T01:58:09.190789Z","end":"2026-03-26T01:58:09.431711Z","steps":["trace[1649657011] 'count revisions from in-memory index tree' (duration: 240.724693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:09.431719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.585837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:58:09.43181Z","caller":"traceutil/trace.go:171","msg":"trace[1563774443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"474.722321ms","start":"2026-03-26T01:58:08.957064Z","end":"2026-03-26T01:58:09.431786Z","steps":["trace[1563774443] 'range keys from in-memory index tree' (duration: 474.346081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:09.431864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:08.957042Z","time spent":"474.808694ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-26T01:58:09.43175Z","caller":"traceutil/trace.go:171","msg":"trace[947029174] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1113; }","duration":"702.465186ms","start":"2026-03-26T01:58:08.729264Z","end":"2026-03-26T01:58:09.431729Z","steps":["trace[947029174] 'count revisions from in-memory index tree' (duration: 702.172799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:09.43194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:08.729241Z","time spent":"702.689493ms","remote":"127.0.0.1:36448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-03-26T01:58:09.431704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.323359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-26T01:58:09.432049Z","caller":"traceutil/trace.go:171","msg":"trace[1888863557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"419.69747ms","start":"2026-03-26T01:58:09.012336Z","end":"2026-03-26T01:58:09.432033Z","steps":["trace[1888863557] 'range keys from in-memory index tree' (duration: 419.151654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:09.432101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:09.012305Z","time spent":"419.778601ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-26T01:58:09.636367Z","caller":"traceutil/trace.go:171","msg":"trace[383451272] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"200.554549ms","start":"2026-03-26T01:58:09.435782Z","end":"2026-03-26T01:58:09.636336Z","steps":["trace[383451272] 'process raft request' (duration: 200.322923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:10.522104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.831306ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:10.522215Z","caller":"traceutil/trace.go:171","msg":"trace[2077942554] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1114; }","duration":"141.951419ms","start":"2026-03-26T01:58:10.380246Z","end":"2026-03-26T01:58:10.522198Z","steps":["trace[2077942554] 'range keys from in-memory index tree' (duration: 141.815536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:10.52257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.220999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:58:10.523155Z","caller":"traceutil/trace.go:171","msg":"trace[1264499464] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"1.086499556s","start":"2026-03-26T01:58:09.436635Z","end":"2026-03-26T01:58:10.523135Z","steps":["trace[1264499464] 'process raft request' (duration: 545.640789ms)","trace[1264499464] 'compare' (duration: 540.060665ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:10.523238Z","caller":"traceutil/trace.go:171","msg":"trace[671793381] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"300.388532ms","start":"2026-03-26T01:58:10.222831Z","end":"2026-03-26T01:58:10.52322Z","steps":["trace[671793381] 'process raft request' (duration: 300.349871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:10.523263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:09.436617Z","time spent":"1.086583728s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:58:10.523328Z","caller":"traceutil/trace.go:171","msg":"trace[2099948511] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1159; }","duration":"381.690175ms","start":"2026-03-26T01:58:10.141625Z","end":"2026-03-26T01:58:10.523315Z","steps":["trace[2099948511] 'read index received' (duration: 31.531µs)","trace[2099948511] 'applied index is now lower than readState.Index' (duration: 381.657004ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:10.523463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.836419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:10.523405Z","caller":"traceutil/trace.go:171","msg":"trace[2142734828] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"1.086543628s","start":"2026-03-26T01:58:09.436755Z","end":"2026-03-26T01:58:10.523299Z","steps":["trace[2142734828] 'process raft request' (duration: 1.08591549s)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:10.523499Z","caller":"traceutil/trace.go:171","msg":"trace[1226716816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1117; }","duration":"381.910621ms","start":"2026-03-26T01:58:10.141579Z","end":"2026-03-26T01:58:10.523489Z","steps":["trace[1226716816] 'agreement among raft nodes before linearized reading' (duration: 381.778947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:10.523755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:09.43673Z","time spent":"1.086882576s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:58:10.523868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:10.14156Z","time spent":"382.27462ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T01:58:10.524231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:10.222806Z","time spent":"300.471495ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:58:11.007509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.680907ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-26T01:58:11.007799Z","caller":"traceutil/trace.go:171","msg":"trace[571189391] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"482.872715ms","start":"2026-03-26T01:58:10.524891Z","end":"2026-03-26T01:58:11.007764Z","steps":["trace[571189391] 'read index received' (duration: 188.92931ms)","trace[571189391] 'applied index is now lower than readState.Index' (duration: 293.940005ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:11.007951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.04599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:11.008074Z","caller":"traceutil/trace.go:171","msg":"trace[1981277415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1118; }","duration":"483.176523ms","start":"2026-03-26T01:58:10.524859Z","end":"2026-03-26T01:58:11.008035Z","steps":["trace[1981277415] 'agreement among raft nodes before linearized reading' (duration: 482.997209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:11.008144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.808496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-26T01:58:11.008151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.159965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-26T01:58:11.008187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:10.524846Z","time spent":"483.267346ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T01:58:11.008208Z","caller":"traceutil/trace.go:171","msg":"trace[22897954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1118; }","duration":"387.251548ms","start":"2026-03-26T01:58:10.620943Z","end":"2026-03-26T01:58:11.008195Z","steps":["trace[22897954] 'agreement among raft nodes before linearized reading' (duration: 387.022282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:11.008431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:10.620924Z","time spent":"387.489274ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-26T01:58:11.008203Z","caller":"traceutil/trace.go:171","msg":"trace[1205789228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"386.915289ms","start":"2026-03-26T01:58:10.621272Z","end":"2026-03-26T01:58:11.008187Z","steps":["trace[1205789228] 'agreement among raft nodes before linearized reading' (duration: 386.720043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:11.008584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:10.621254Z","time spent":"387.313429ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-26T01:58:30.857757Z","caller":"traceutil/trace.go:171","msg":"trace[520856744] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"165.403694ms","start":"2026-03-26T01:58:30.692337Z","end":"2026-03-26T01:58:30.85774Z","steps":["trace[520856744] 'read index received' (duration: 165.196718ms)","trace[520856744] 'applied index is now lower than readState.Index' (duration: 206.316µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:30.857805Z","caller":"traceutil/trace.go:171","msg":"trace[791861015] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"239.587422ms","start":"2026-03-26T01:58:30.61819Z","end":"2026-03-26T01:58:30.857777Z","steps":["trace[791861015] 'process raft request' (duration: 239.382846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:30.857973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.166367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:58:30.857997Z","caller":"traceutil/trace.go:171","msg":"trace[1192368717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1233; }","duration":"165.217349ms","start":"2026-03-26T01:58:30.692771Z","end":"2026-03-26T01:58:30.857989Z","steps":["trace[1192368717] 'agreement among raft nodes before linearized reading' (duration: 165.106116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:30.858046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.685252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T01:58:30.858146Z","caller":"traceutil/trace.go:171","msg":"trace[1310396139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"165.763834ms","start":"2026-03-26T01:58:30.692331Z","end":"2026-03-26T01:58:30.858095Z","steps":["trace[1310396139] 'agreement among raft nodes before linearized reading' (duration: 165.532597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:31.69296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.307509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:58:31.693205Z","caller":"traceutil/trace.go:171","msg":"trace[1343324040] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"108.730045ms","start":"2026-03-26T01:58:31.584458Z","end":"2026-03-26T01:58:31.693188Z","steps":["trace[1343324040] 'process raft request' (duration: 108.605992ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:31.693284Z","caller":"traceutil/trace.go:171","msg":"trace[308990912] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"545.931207ms","start":"2026-03-26T01:58:31.147299Z","end":"2026-03-26T01:58:31.69323Z","steps":["trace[308990912] 'process raft request' (duration: 182.278928ms)","trace[308990912] 'compare' (duration: 363.177086ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:31.693451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:31.147294Z","time spent":"546.06665ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:58:32.975085Z","caller":"traceutil/trace.go:171","msg":"trace[1812379916] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"111.169784ms","start":"2026-03-26T01:58:32.86389Z","end":"2026-03-26T01:58:32.975059Z","steps":["trace[1812379916] 'process raft request' (duration: 111.039171ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:32.975517Z","caller":"traceutil/trace.go:171","msg":"trace[1196403899] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1292; }","duration":"110.612408ms","start":"2026-03-26T01:58:32.864892Z","end":"2026-03-26T01:58:32.975505Z","steps":["trace[1196403899] 'read index received' (duration: 110.607398ms)","trace[1196403899] 'applied index is now lower than readState.Index' (duration: 4.06µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:32.975685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.358014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T01:58:32.975728Z","caller":"traceutil/trace.go:171","msg":"trace[1984871397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"109.433896ms","start":"2026-03-26T01:58:32.866283Z","end":"2026-03-26T01:58:32.975717Z","steps":["trace[1984871397] 'agreement among raft nodes before linearized reading' (duration: 109.293672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:32.976033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.136804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:58:32.976063Z","caller":"traceutil/trace.go:171","msg":"trace[1767204680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1240; }","duration":"111.194184ms","start":"2026-03-26T01:58:32.86486Z","end":"2026-03-26T01:58:32.976054Z","steps":["trace[1767204680] 'agreement among raft nodes before linearized reading' (duration: 111.108712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:33.639394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.447715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:58:33.639558Z","caller":"traceutil/trace.go:171","msg":"trace[1645842984] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1292; }","duration":"399.984618ms","start":"2026-03-26T01:58:33.239557Z","end":"2026-03-26T01:58:33.639542Z","steps":["trace[1645842984] 'read index received' (duration: 151.316827ms)","trace[1645842984] 'applied index is now lower than readState.Index' (duration: 248.666821ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:33.639601Z","caller":"traceutil/trace.go:171","msg":"trace[1416021501] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"659.321672ms","start":"2026-03-26T01:58:32.980255Z","end":"2026-03-26T01:58:33.639576Z","steps":["trace[1416021501] 'process raft request' (duration: 659.237109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:33.639731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:32.980246Z","time spent":"659.414525ms","remote":"127.0.0.1:40684","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-03-26T01:58:33.639751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.204324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-26T01:58:33.639783Z","caller":"traceutil/trace.go:171","msg":"trace[1625724301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"400.315867ms","start":"2026-03-26T01:58:33.239457Z","end":"2026-03-26T01:58:33.639773Z","steps":["trace[1625724301] 'agreement among raft nodes before linearized reading' (duration: 400.180773ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:33.639798Z","caller":"traceutil/trace.go:171","msg":"trace[1550331810] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"659.63103ms","start":"2026-03-26T01:58:32.980138Z","end":"2026-03-26T01:58:33.639769Z","steps":["trace[1550331810] 'process raft request' (duration: 410.720222ms)","trace[1550331810] 'compare' (duration: 248.346623ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:33.639936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:32.980122Z","time spent":"659.742174ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:58:33.639823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:33.239435Z","time spent":"400.379818ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-26T01:58:37.79358Z","caller":"traceutil/trace.go:171","msg":"trace[1612177264] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"136.040417ms","start":"2026-03-26T01:58:37.657514Z","end":"2026-03-26T01:58:37.793554Z","steps":["trace[1612177264] 'process raft request' (duration: 135.311567ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:37.794403Z","caller":"traceutil/trace.go:171","msg":"trace[115448291] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"130.298479ms","start":"2026-03-26T01:58:37.664091Z","end":"2026-03-26T01:58:37.79439Z","steps":["trace[115448291] 'process raft request' (duration: 130.108453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:50.004562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.014153ms","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-03-26T01:58:50.004618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.004881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-26T01:58:50.004641Z","caller":"traceutil/trace.go:171","msg":"trace[521043046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1314; }","duration":"141.119576ms","start":"2026-03-26T01:58:49.863504Z","end":"2026-03-26T01:58:50.004624Z","steps":["trace[521043046] 'range keys from in-memory index tree' (duration: 140.88057ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:50.004676Z","caller":"traceutil/trace.go:171","msg":"trace[19875525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1314; }","duration":"142.101813ms","start":"2026-03-26T01:58:49.86256Z","end":"2026-03-26T01:58:50.004662Z","steps":["trace[19875525] 'range keys from in-memory index tree' (duration: 141.891528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:50.004655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.23102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-26T01:58:50.005079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.427203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T01:58:50.005065Z","caller":"traceutil/trace.go:171","msg":"trace[2081461897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"141.60484ms","start":"2026-03-26T01:58:49.863376Z","end":"2026-03-26T01:58:50.004981Z","steps":["trace[2081461897] 'range keys from in-memory index tree' (duration: 141.083455ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:50.005136Z","caller":"traceutil/trace.go:171","msg":"trace[184982857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1314; }","duration":"142.525065ms","start":"2026-03-26T01:58:49.862597Z","end":"2026-03-26T01:58:50.005122Z","steps":["trace[184982857] 'range keys from in-memory index tree' (duration: 142.324439ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:50.251921Z","caller":"traceutil/trace.go:171","msg":"trace[146330762] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"242.485132ms","start":"2026-03-26T01:58:50.009404Z","end":"2026-03-26T01:58:50.251889Z","steps":["trace[146330762] 'process raft request' (duration: 232.925249ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:50.252198Z","caller":"traceutil/trace.go:171","msg":"trace[124714233] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1370; }","duration":"150.187505ms","start":"2026-03-26T01:58:50.101978Z","end":"2026-03-26T01:58:50.252165Z","steps":["trace[124714233] 'read index received' (duration: 6.824787ms)","trace[124714233] 'applied index is now lower than readState.Index' (duration: 143.361758ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:50.252261Z","caller":"traceutil/trace.go:171","msg":"trace[112465215] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"242.748648ms","start":"2026-03-26T01:58:50.009485Z","end":"2026-03-26T01:58:50.252234Z","steps":["trace[112465215] 'process raft request' (duration: 242.348067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:50.252356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.618306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T01:58:50.252349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.323549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T01:58:50.252393Z","caller":"traceutil/trace.go:171","msg":"trace[1045169513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1318; }","duration":"111.700308ms","start":"2026-03-26T01:58:50.140682Z","end":"2026-03-26T01:58:50.252382Z","steps":["trace[1045169513] 'agreement among raft nodes before linearized reading' (duration: 111.621546ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:50.252437Z","caller":"traceutil/trace.go:171","msg":"trace[838240266] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1318; }","duration":"150.473563ms","start":"2026-03-26T01:58:50.101942Z","end":"2026-03-26T01:58:50.252415Z","steps":["trace[838240266] 'agreement among raft nodes before linearized reading' (duration: 150.308088ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:50.252872Z","caller":"traceutil/trace.go:171","msg":"trace[1132465094] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"242.557063ms","start":"2026-03-26T01:58:50.010293Z","end":"2026-03-26T01:58:50.25285Z","steps":["trace[1132465094] 'process raft request' (duration: 241.793912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:50.548284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.932854ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-26T01:58:50.548314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.354647ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:50.548413Z","caller":"traceutil/trace.go:171","msg":"trace[1012104510] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1318; }","duration":"167.478641ms","start":"2026-03-26T01:58:50.380917Z","end":"2026-03-26T01:58:50.548396Z","steps":["trace[1012104510] 'range keys from in-memory index tree' (duration: 167.335727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:55.372498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.643352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:58:55.372647Z","caller":"traceutil/trace.go:171","msg":"trace[2090563591] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"225.330769ms","start":"2026-03-26T01:58:55.147296Z","end":"2026-03-26T01:58:55.372627Z","steps":["trace[2090563591] 'read index received' (duration: 48.424409ms)","trace[2090563591] 'applied index is now lower than readState.Index' (duration: 176.90462ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:55.372706Z","caller":"traceutil/trace.go:171","msg":"trace[832112200] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"406.297821ms","start":"2026-03-26T01:58:54.966373Z","end":"2026-03-26T01:58:55.372671Z","steps":["trace[832112200] 'process raft request' (duration: 229.369061ms)","trace[832112200] 'compare' (duration: 176.480118ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:55.372746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.453113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:55.372778Z","caller":"traceutil/trace.go:171","msg":"trace[897491020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1353; }","duration":"225.508624ms","start":"2026-03-26T01:58:55.14726Z","end":"2026-03-26T01:58:55.372769Z","steps":["trace[897491020] 'agreement among raft nodes before linearized reading' (duration: 225.416731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:55.372861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:54.966336Z","time spent":"406.438175ms","remote":"127.0.0.1:40436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:58:55.433766Z","caller":"traceutil/trace.go:171","msg":"trace[1903613110] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"242.220114ms","start":"2026-03-26T01:58:55.191512Z","end":"2026-03-26T01:58:55.433732Z","steps":["trace[1903613110] 'process raft request' (duration: 242.055899ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:55.942344Z","caller":"traceutil/trace.go:171","msg":"trace[846031097] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"125.384595ms","start":"2026-03-26T01:58:55.81693Z","end":"2026-03-26T01:58:55.942315Z","steps":["trace[846031097] 'process raft request' (duration: 125.241361ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:56.316889Z","caller":"traceutil/trace.go:171","msg":"trace[1202386508] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"184.107278ms","start":"2026-03-26T01:58:56.132755Z","end":"2026-03-26T01:58:56.316863Z","steps":["trace[1202386508] 'process raft request' (duration: 183.870601ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:56.316802Z","caller":"traceutil/trace.go:171","msg":"trace[998385507] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"177.97378ms","start":"2026-03-26T01:58:56.138807Z","end":"2026-03-26T01:58:56.316781Z","steps":["trace[998385507] 'read index received' (duration: 177.523857ms)","trace[998385507] 'applied index is now lower than readState.Index' (duration: 449.083µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:56.317057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.237197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:56.317088Z","caller":"traceutil/trace.go:171","msg":"trace[142060957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"178.293108ms","start":"2026-03-26T01:58:56.138787Z","end":"2026-03-26T01:58:56.31708Z","steps":["trace[142060957] 'agreement among raft nodes before linearized reading' (duration: 178.232656ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:56.494849Z","caller":"traceutil/trace.go:171","msg":"trace[1811795438] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"145.159857ms","start":"2026-03-26T01:58:56.349666Z","end":"2026-03-26T01:58:56.494826Z","steps":["trace[1811795438] 'process raft request' (duration: 68.846301ms)","trace[1811795438] 'compare' (duration: 76.037229ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:56.495084Z","caller":"traceutil/trace.go:171","msg":"trace[1822903425] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"123.507153ms","start":"2026-03-26T01:58:56.371556Z","end":"2026-03-26T01:58:56.49505Z","steps":["trace[1822903425] 'process raft request' (duration: 123.40289ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:56.9954Z","caller":"traceutil/trace.go:171","msg":"trace[1586318826] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"267.209ms","start":"2026-03-26T01:58:56.728165Z","end":"2026-03-26T01:58:56.995374Z","steps":["trace[1586318826] 'process raft request' (duration: 267.035795ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:56.995739Z","caller":"traceutil/trace.go:171","msg":"trace[1323716525] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"215.441108ms","start":"2026-03-26T01:58:56.780284Z","end":"2026-03-26T01:58:56.995725Z","steps":["trace[1323716525] 'read index received' (duration: 214.866383ms)","trace[1323716525] 'applied index is now lower than readState.Index' (duration: 573.225µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:56.995828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.546372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:56.995899Z","caller":"traceutil/trace.go:171","msg":"trace[925451071] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1360; }","duration":"215.669155ms","start":"2026-03-26T01:58:56.780218Z","end":"2026-03-26T01:58:56.995888Z","steps":["trace[925451071] 'agreement among raft nodes before linearized reading' (duration: 215.554422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:58.052843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004679029s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:58:58.052952Z","caller":"traceutil/trace.go:171","msg":"trace[1579243064] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"911.303065ms","start":"2026-03-26T01:58:57.141624Z","end":"2026-03-26T01:58:58.052927Z","steps":["trace[1579243064] 'read index received' (duration: 39.141µs)","trace[1579243064] 'applied index is now lower than readState.Index' (duration: 911.262014ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:58.053021Z","caller":"traceutil/trace.go:171","msg":"trace[282957496] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.159984416s","start":"2026-03-26T01:58:56.893025Z","end":"2026-03-26T01:58:58.05301Z","steps":["trace[282957496] 'process raft request' (duration: 155.027019ms)","trace[282957496] 'compare' (duration: 1.004254498s)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:58.053084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:56.893001Z","time spent":"1.160050768s","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T01:58:58.053292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.672504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:58.053328Z","caller":"traceutil/trace.go:171","msg":"trace[76094119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"911.742056ms","start":"2026-03-26T01:58:57.141576Z","end":"2026-03-26T01:58:58.053318Z","steps":["trace[76094119] 'agreement among raft nodes before linearized reading' (duration: 911.680755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:58.053355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:57.141556Z","time spent":"911.794128ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T01:58:58.141595Z","caller":"traceutil/trace.go:171","msg":"trace[480406386] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"702.093126ms","start":"2026-03-26T01:58:57.439474Z","end":"2026-03-26T01:58:58.141567Z","steps":["trace[480406386] 'process raft request' (duration: 701.813479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:58.141719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.555623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-03-26T01:58:58.141749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:58:57.439446Z","time spent":"702.22348ms","remote":"127.0.0.1:40684","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-03-26T01:58:58.141793Z","caller":"traceutil/trace.go:171","msg":"trace[1490057554] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1362; }","duration":"191.681776ms","start":"2026-03-26T01:58:57.950093Z","end":"2026-03-26T01:58:58.141775Z","steps":["trace[1490057554] 'agreement among raft nodes before linearized reading' (duration: 191.46681ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:58:58.14196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.439289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:58:58.14201Z","caller":"traceutil/trace.go:171","msg":"trace[669235633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1362; }","duration":"195.544511ms","start":"2026-03-26T01:58:57.946454Z","end":"2026-03-26T01:58:58.141999Z","steps":["trace[669235633] 'agreement among raft nodes before linearized reading' (duration: 195.438458ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:58.28962Z","caller":"traceutil/trace.go:171","msg":"trace[1594756701] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1419; }","duration":"124.994154ms","start":"2026-03-26T01:58:58.1646Z","end":"2026-03-26T01:58:58.289594Z","steps":["trace[1594756701] 'read index received' (duration: 45.254833ms)","trace[1594756701] 'applied index is now lower than readState.Index' (duration: 79.738151ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:58.289784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.156038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:58:58.289829Z","caller":"traceutil/trace.go:171","msg":"trace[1380873179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"125.262591ms","start":"2026-03-26T01:58:58.164553Z","end":"2026-03-26T01:58:58.289816Z","steps":["trace[1380873179] 'agreement among raft nodes before linearized reading' (duration: 125.169058ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:58.289977Z","caller":"traceutil/trace.go:171","msg":"trace[1113455011] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"126.001671ms","start":"2026-03-26T01:58:58.163921Z","end":"2026-03-26T01:58:58.289923Z","steps":["trace[1113455011] 'process raft request' (duration: 125.60287ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:58:58.290085Z","caller":"traceutil/trace.go:171","msg":"trace[908207830] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"143.499542ms","start":"2026-03-26T01:58:58.14655Z","end":"2026-03-26T01:58:58.290049Z","steps":["trace[908207830] 'process raft request' (duration: 63.33704ms)","trace[908207830] 'compare' (duration: 79.283908ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:58:58.550124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.916227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:58:58.550379Z","caller":"traceutil/trace.go:171","msg":"trace[1347802750] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"174.988657ms","start":"2026-03-26T01:58:58.375359Z","end":"2026-03-26T01:58:58.550348Z","steps":["trace[1347802750] 'process raft request' (duration: 71.767701ms)","trace[1347802750] 'compare' (duration: 102.795504ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:58:58.788737Z","caller":"traceutil/trace.go:171","msg":"trace[1231533235] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"232.834526ms","start":"2026-03-26T01:58:58.555873Z","end":"2026-03-26T01:58:58.788708Z","steps":["trace[1231533235] 'process raft request' (duration: 232.683082ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:59:00.416746Z","caller":"traceutil/trace.go:171","msg":"trace[12802321] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1427; }","duration":"122.256147ms","start":"2026-03-26T01:59:00.294465Z","end":"2026-03-26T01:59:00.416721Z","steps":["trace[12802321] 'read index received' (duration: 68.371027ms)","trace[12802321] 'applied index is now lower than readState.Index' (duration: 53.88388ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:59:00.417008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.533765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T01:59:00.417074Z","caller":"traceutil/trace.go:171","msg":"trace[446206996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1370; }","duration":"122.650489ms","start":"2026-03-26T01:59:00.294407Z","end":"2026-03-26T01:59:00.417057Z","steps":["trace[446206996] 'agreement among raft nodes before linearized reading' (duration: 122.442193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:59:00.85262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.799577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T01:59:00.852696Z","caller":"traceutil/trace.go:171","msg":"trace[892469951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1372; }","duration":"298.916001ms","start":"2026-03-26T01:59:00.553764Z","end":"2026-03-26T01:59:00.85268Z","steps":["trace[892469951] 'range keys from in-memory index tree' (duration: 298.646754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:59:00.852855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.52819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-26T01:59:00.852938Z","caller":"traceutil/trace.go:171","msg":"trace[475128475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1372; }","duration":"219.651563ms","start":"2026-03-26T01:59:00.633262Z","end":"2026-03-26T01:59:00.852914Z","steps":["trace[475128475] 'range keys from in-memory index tree' (duration: 219.351026ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:59:01.046694Z","caller":"traceutil/trace.go:171","msg":"trace[1983316352] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"189.30207ms","start":"2026-03-26T01:59:00.857339Z","end":"2026-03-26T01:59:01.046641Z","steps":["trace[1983316352] 'process raft request' (duration: 189.093765ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:59:01.150049Z","caller":"traceutil/trace.go:171","msg":"trace[958979001] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"292.649769ms","start":"2026-03-26T01:59:00.857382Z","end":"2026-03-26T01:59:01.150031Z","steps":["trace[958979001] 'process raft request' (duration: 217.67289ms)","trace[958979001] 'compare' (duration: 74.857906ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:59:01.150331Z","caller":"traceutil/trace.go:171","msg":"trace[1311481894] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"292.867085ms","start":"2026-03-26T01:59:00.85744Z","end":"2026-03-26T01:59:01.150307Z","steps":["trace[1311481894] 'process raft request' (duration: 292.565227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:59:02.156371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.317601ms","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":"warn","ts":"2026-03-26T01:59:02.156426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.667137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:59:02.156505Z","caller":"traceutil/trace.go:171","msg":"trace[563496324] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1375; }","duration":"728.78833ms","start":"2026-03-26T01:59:01.427702Z","end":"2026-03-26T01:59:02.15649Z","steps":["trace[563496324] 'count revisions from in-memory index tree' (duration: 728.603915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:59:02.156536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:59:01.427687Z","time spent":"728.841531ms","remote":"127.0.0.1:36312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-03-26T01:59:02.156472Z","caller":"traceutil/trace.go:171","msg":"trace[835951959] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1375; }","duration":"157.459515ms","start":"2026-03-26T01:59:01.998993Z","end":"2026-03-26T01:59:02.156453Z","steps":["trace[835951959] 'range keys from in-memory index tree' (duration: 157.163877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:59:02.641999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.328201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T01:59:02.642127Z","caller":"traceutil/trace.go:171","msg":"trace[2006806539] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"175.546192ms","start":"2026-03-26T01:59:02.466559Z","end":"2026-03-26T01:59:02.642105Z","steps":["trace[2006806539] 'read index received' (duration: 88.022µs)","trace[2006806539] 'applied index is now lower than readState.Index' (duration: 175.45592ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:59:02.642185Z","caller":"traceutil/trace.go:171","msg":"trace[155012641] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"461.662662ms","start":"2026-03-26T01:59:02.180504Z","end":"2026-03-26T01:59:02.642167Z","steps":["trace[155012641] 'process raft request' (duration: 185.055133ms)","trace[155012641] 'compare' (duration: 276.036583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T01:59:02.642368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.78998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-26T01:59:02.642392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T01:59:02.180479Z","time spent":"461.868698ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T01:59:02.642426Z","caller":"traceutil/trace.go:171","msg":"trace[55241762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1378; }","duration":"175.902173ms","start":"2026-03-26T01:59:02.466509Z","end":"2026-03-26T01:59:02.642411Z","steps":["trace[55241762] 'agreement among raft nodes before linearized reading' (duration: 175.708927ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:59:02.642581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.557374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T01:59:02.642997Z","caller":"traceutil/trace.go:171","msg":"trace[1860229310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1378; }","duration":"132.020976ms","start":"2026-03-26T01:59:02.510958Z","end":"2026-03-26T01:59:02.642979Z","steps":["trace[1860229310] 'agreement among raft nodes before linearized reading' (duration: 131.492852ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:59:02.847135Z","caller":"traceutil/trace.go:171","msg":"trace[2137125542] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"200.487048ms","start":"2026-03-26T01:59:02.646627Z","end":"2026-03-26T01:59:02.847114Z","steps":["trace[2137125542] 'process raft request' (duration: 200.366874ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:59:02.847821Z","caller":"traceutil/trace.go:171","msg":"trace[1389222782] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"200.635381ms","start":"2026-03-26T01:59:02.647162Z","end":"2026-03-26T01:59:02.847797Z","steps":["trace[1389222782] 'process raft request' (duration: 200.540439ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:59:04.298653Z","caller":"traceutil/trace.go:171","msg":"trace[560028681] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"202.647476ms","start":"2026-03-26T01:59:04.095976Z","end":"2026-03-26T01:59:04.298624Z","steps":["trace[560028681] 'process raft request' (duration: 199.14505ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:59:04.299304Z","caller":"traceutil/trace.go:171","msg":"trace[1069204453] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1448; }","duration":"158.543246ms","start":"2026-03-26T01:59:04.14074Z","end":"2026-03-26T01:59:04.299284Z","steps":["trace[1069204453] 'read index received' (duration: 154.287709ms)","trace[1069204453] 'applied index is now lower than readState.Index' (duration: 4.254017ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T01:59:04.299327Z","caller":"traceutil/trace.go:171","msg":"trace[1787122173] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"199.11244ms","start":"2026-03-26T01:59:04.100184Z","end":"2026-03-26T01:59:04.299296Z","steps":["trace[1787122173] 'process raft request' (duration: 198.300578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:59:04.299538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.550507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:59:04.299618Z","caller":"traceutil/trace.go:171","msg":"trace[11839344] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1392; }","duration":"122.658859ms","start":"2026-03-26T01:59:04.176945Z","end":"2026-03-26T01:59:04.299604Z","steps":["trace[11839344] 'agreement among raft nodes before linearized reading' (duration: 122.535896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T01:59:04.29956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.753261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T01:59:04.299769Z","caller":"traceutil/trace.go:171","msg":"trace[2042199181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1392; }","duration":"159.017019ms","start":"2026-03-26T01:59:04.1407Z","end":"2026-03-26T01:59:04.299717Z","steps":["trace[2042199181] 'agreement among raft nodes before linearized reading' (duration: 158.647768ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T01:59:11.343953Z","caller":"traceutil/trace.go:171","msg":"trace[363253806] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"103.196085ms","start":"2026-03-26T01:59:11.240731Z","end":"2026-03-26T01:59:11.343927Z","steps":["trace[363253806] 'process raft request' (duration: 98.662151ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T02:02:05.716959Z","caller":"traceutil/trace.go:171","msg":"trace[1241586093] transaction","detail":"{read_only:false; response_revision:2255; number_of_response:1; }","duration":"149.830078ms","start":"2026-03-26T02:02:05.567098Z","end":"2026-03-26T02:02:05.716928Z","steps":["trace[1241586093] 'process raft request' (duration: 149.604462ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T02:02:05.717487Z","caller":"traceutil/trace.go:171","msg":"trace[967011058] linearizableReadLoop","detail":"{readStateIndex:2352; appliedIndex:2350; }","duration":"128.591309ms","start":"2026-03-26T02:02:05.588877Z","end":"2026-03-26T02:02:05.717468Z","steps":["trace[967011058] 'read index received' (duration: 127.798057ms)","trace[967011058] 'applied index is now lower than readState.Index' (duration: 792.532µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T02:02:05.717738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.125156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T02:02:05.717743Z","caller":"traceutil/trace.go:171","msg":"trace[2005564298] transaction","detail":"{read_only:false; response_revision:2256; number_of_response:1; }","duration":"134.404027ms","start":"2026-03-26T02:02:05.583324Z","end":"2026-03-26T02:02:05.717728Z","steps":["trace[2005564298] 'process raft request' (duration: 134.031677ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T02:02:05.717772Z","caller":"traceutil/trace.go:171","msg":"trace[371838894] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2256; }","duration":"102.178828ms","start":"2026-03-26T02:02:05.615584Z","end":"2026-03-26T02:02:05.717762Z","steps":["trace[371838894] 'agreement among raft nodes before linearized reading' (duration: 101.991913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T02:02:05.717802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.895487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-26T02:02:05.71789Z","caller":"traceutil/trace.go:171","msg":"trace[354045840] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2256; }","duration":"129.01835ms","start":"2026-03-26T02:02:05.588851Z","end":"2026-03-26T02:02:05.717869Z","steps":["trace[354045840] 'agreement among raft nodes before linearized reading' (duration: 128.721641ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T02:02:15.868365Z","caller":"traceutil/trace.go:171","msg":"trace[1933925756] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"105.855799ms","start":"2026-03-26T02:02:15.762493Z","end":"2026-03-26T02:02:15.868349Z","steps":["trace[1933925756] 'process raft request' (duration: 105.319304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T02:02:16.589836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.257263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T02:02:16.590123Z","caller":"traceutil/trace.go:171","msg":"trace[1790040934] linearizableReadLoop","detail":"{readStateIndex:2410; appliedIndex:2408; }","duration":"513.525192ms","start":"2026-03-26T02:02:16.076581Z","end":"2026-03-26T02:02:16.590106Z","steps":["trace[1790040934] 'read index received' (duration: 370.353756ms)","trace[1790040934] 'applied index is now lower than readState.Index' (duration: 143.170466ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T02:02:16.59045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.852951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-26T02:02:16.59054Z","caller":"traceutil/trace.go:171","msg":"trace[1170055940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2312; }","duration":"513.982294ms","start":"2026-03-26T02:02:16.076536Z","end":"2026-03-26T02:02:16.590518Z","steps":["trace[1170055940] 'agreement among raft nodes before linearized reading' (duration: 513.697757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T02:02:16.590596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T02:02:16.076521Z","time spent":"514.062027ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-26T02:02:16.59069Z","caller":"traceutil/trace.go:171","msg":"trace[1836641344] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"718.399292ms","start":"2026-03-26T02:02:15.872247Z","end":"2026-03-26T02:02:16.590646Z","steps":["trace[1836641344] 'process raft request' (duration: 137.239465ms)","trace[1836641344] 'compare' (duration: 580.149809ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T02:02:16.590836Z","caller":"traceutil/trace.go:171","msg":"trace[1313007250] transaction","detail":"{read_only:false; response_revision:2312; number_of_response:1; }","duration":"630.73162ms","start":"2026-03-26T02:02:15.96009Z","end":"2026-03-26T02:02:16.590822Z","steps":["trace[1313007250] 'process raft request' (duration: 629.962869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T02:02:16.59087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T02:02:15.872228Z","time spent":"718.555487ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T02:02:16.590972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.897266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-26T02:02:16.590964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.39444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T02:02:16.590999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T02:02:15.960057Z","time spent":"630.864183ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T02:02:16.591015Z","caller":"traceutil/trace.go:171","msg":"trace[2016427256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2312; }","duration":"335.979018ms","start":"2026-03-26T02:02:16.255023Z","end":"2026-03-26T02:02:16.591002Z","steps":["trace[2016427256] 'agreement among raft nodes before linearized reading' (duration: 335.850934ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T02:02:16.591045Z","caller":"traceutil/trace.go:171","msg":"trace[91231977] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2312; }","duration":"247.522723ms","start":"2026-03-26T02:02:16.343503Z","end":"2026-03-26T02:02:16.591026Z","steps":["trace[91231977] 'agreement among raft nodes before linearized reading' (duration: 247.352839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T02:02:16.591054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T02:02:16.25499Z","time spent":"336.05588ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T02:02:16.590646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.331046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-26T02:02:16.591143Z","caller":"traceutil/trace.go:171","msg":"trace[1993253481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2312; }","duration":"506.83881ms","start":"2026-03-26T02:02:16.084278Z","end":"2026-03-26T02:02:16.591116Z","steps":["trace[1993253481] 'agreement among raft nodes before linearized reading' (duration: 506.190472ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T02:02:16.591187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.275051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-26T02:02:16.591206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T02:02:16.084262Z","time spent":"506.925542ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T02:02:16.591213Z","caller":"traceutil/trace.go:171","msg":"trace[2034412526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2312; }","duration":"452.334612ms","start":"2026-03-26T02:02:16.13887Z","end":"2026-03-26T02:02:16.591205Z","steps":["trace[2034412526] 'agreement among raft nodes before linearized reading' (duration: 452.297391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T02:02:16.591237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T02:02:16.138853Z","time spent":"452.379323ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T02:02:16.591322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.815542ms","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-03-26T02:02:16.591356Z","caller":"traceutil/trace.go:171","msg":"trace[261944165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2312; }","duration":"107.889464ms","start":"2026-03-26T02:02:16.483457Z","end":"2026-03-26T02:02:16.591346Z","steps":["trace[261944165] 'agreement among raft nodes before linearized reading' (duration: 107.76014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T02:02:17.229687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.244376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-26T02:02:17.229758Z","caller":"traceutil/trace.go:171","msg":"trace[631056108] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2317; }","duration":"250.37837ms","start":"2026-03-26T02:02:16.979366Z","end":"2026-03-26T02:02:17.229745Z","steps":["trace[631056108] 'count revisions from in-memory index tree' (duration: 250.151244ms)"],"step_count":1}