{"level":"warn","ts":"2026-04-16T02:54:39.959936Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T02:54:39.960036Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.75:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.75:2380","--initial-cluster=instance=https://199.19.213.75:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.75:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.75:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-16T02:54:39.960114Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T02:54:39.960132Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-04-16T02:54:39.960154Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T02:54:39.960803Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"]} {"level":"info","ts":"2026-04-16T02:54:39.960888Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.75:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-16T02:54:39.965369Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.215512ms"} {"level":"info","ts":"2026-04-16T02:54:39.974853Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"42c325ad8bcf8012","cluster-id":"1e2ab336c559df5e"} {"level":"info","ts":"2026-04-16T02:54:39.975034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T02:54:39.975091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 0"} {"level":"info","ts":"2026-04-16T02:54:39.975112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 42c325ad8bcf8012 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T02:54:39.975123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became follower at term 1"} {"level":"info","ts":"2026-04-16T02:54:39.975171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"warn","ts":"2026-04-16T02:54:39.988595Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T02:54:39.990698Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T02:54:39.991759Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-16T02:54:39.994204Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"42c325ad8bcf8012","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T02:54:39.994595Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"42c325ad8bcf8012","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T02:54:39.994737Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T02:54:39.994789Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T02:54:39.9948Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-16T02:54:39.996842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 switched to configuration voters=(4810730254267023378)"} {"level":"info","ts":"2026-04-16T02:54:39.997902Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","added-peer-id":"42c325ad8bcf8012","added-peer-peer-urls":["https://199.19.213.75:2380"]} {"level":"info","ts":"2026-04-16T02:54:40.001933Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-16T02:54:40.002094Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-04-16T02:54:40.002158Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.75:2380"} {"level":"info","ts":"2026-04-16T02:54:40.005382Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"42c325ad8bcf8012","initial-advertise-peer-urls":["https://199.19.213.75:2380"],"listen-peer-urls":["https://199.19.213.75:2380"],"advertise-client-urls":["https://199.19.213.75:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.75:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T02:54:40.005511Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T02:54:40.975598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T02:54:40.975723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T02:54:40.975856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgPreVoteResp from 42c325ad8bcf8012 at term 1"} {"level":"info","ts":"2026-04-16T02:54:40.975946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became candidate at term 2"} {"level":"info","ts":"2026-04-16T02:54:40.975967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 received MsgVoteResp from 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-04-16T02:54:40.975987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"42c325ad8bcf8012 became leader at term 2"} {"level":"info","ts":"2026-04-16T02:54:40.976034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 42c325ad8bcf8012 elected leader 42c325ad8bcf8012 at term 2"} {"level":"info","ts":"2026-04-16T02:54:40.977399Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T02:54:40.978486Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"42c325ad8bcf8012","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.75:2379]}","request-path":"/0/members/42c325ad8bcf8012/attributes","cluster-id":"1e2ab336c559df5e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T02:54:40.978619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T02:54:40.9786Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T02:54:40.981189Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T02:54:40.981236Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T02:54:40.9817Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1e2ab336c559df5e","local-member-id":"42c325ad8bcf8012","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T02:54:40.98193Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T02:54:40.982047Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T02:54:40.982369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.75:2379"} {"level":"info","ts":"2026-04-16T02:54:40.98379Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T02:54:55.118304Z","caller":"traceutil/trace.go:171","msg":"trace[824648219] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"118.404095ms","start":"2026-04-16T02:54:54.999859Z","end":"2026-04-16T02:54:55.118263Z","steps":["trace[824648219] 'process raft request' (duration: 118.171474ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:07.578683Z","caller":"traceutil/trace.go:171","msg":"trace[1041824882] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"237.113726ms","start":"2026-04-16T02:55:07.341531Z","end":"2026-04-16T02:55:07.578645Z","steps":["trace[1041824882] 'process raft request' (duration: 236.901943ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:07.588901Z","caller":"traceutil/trace.go:171","msg":"trace[1193218937] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"231.529375ms","start":"2026-04-16T02:55:07.357323Z","end":"2026-04-16T02:55:07.588852Z","steps":["trace[1193218937] 'process raft request' (duration: 228.813866ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:07.589677Z","caller":"traceutil/trace.go:171","msg":"trace[1097381702] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"229.638748ms","start":"2026-04-16T02:55:07.359989Z","end":"2026-04-16T02:55:07.589622Z","steps":["trace[1097381702] 'process raft request' (duration: 226.379481ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:07.590246Z","caller":"traceutil/trace.go:171","msg":"trace[1572180686] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"187.453181ms","start":"2026-04-16T02:55:07.402749Z","end":"2026-04-16T02:55:07.590203Z","steps":["trace[1572180686] 'process raft request' (duration: 184.078932ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:08.010508Z","caller":"traceutil/trace.go:171","msg":"trace[2074689131] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"400.608095ms","start":"2026-04-16T02:55:07.609864Z","end":"2026-04-16T02:55:08.010472Z","steps":["trace[2074689131] 'process raft request' (duration: 400.403872ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:08.010508Z","caller":"traceutil/trace.go:171","msg":"trace[20672405] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"404.944208ms","start":"2026-04-16T02:55:07.605529Z","end":"2026-04-16T02:55:08.010473Z","steps":["trace[20672405] 'process raft request' (duration: 377.165774ms)","trace[20672405] 'compare' (duration: 27.282546ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:08.010635Z","caller":"traceutil/trace.go:171","msg":"trace[1506933363] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"320.928837ms","start":"2026-04-16T02:55:07.689693Z","end":"2026-04-16T02:55:08.010621Z","steps":["trace[1506933363] 'process raft request' (duration: 320.704333ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:08.010626Z","caller":"traceutil/trace.go:171","msg":"trace[352183083] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"353.061899ms","start":"2026-04-16T02:55:07.657502Z","end":"2026-04-16T02:55:08.010564Z","steps":["trace[352183083] 'process raft request' (duration: 352.832226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:08.011003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:07.609842Z","time spent":"400.737286ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:08.011046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:07.657483Z","time spent":"353.286053ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T02:55:08.011078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:07.605505Z","time spent":"405.080619ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:08.011202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:07.689659Z","time spent":"321.000868ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:08.806075Z","caller":"traceutil/trace.go:171","msg":"trace[432787296] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"700.007963ms","start":"2026-04-16T02:55:08.106036Z","end":"2026-04-16T02:55:08.806044Z","steps":["trace[432787296] 'process raft request' (duration: 604.428236ms)","trace[432787296] 'compare' (duration: 95.305453ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:08.806221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:08.106001Z","time spent":"700.148975ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:08.816754Z","caller":"traceutil/trace.go:171","msg":"trace[1724452071] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"696.996908ms","start":"2026-04-16T02:55:08.113675Z","end":"2026-04-16T02:55:08.810672Z","steps":["trace[1724452071] 'process raft request' (duration: 696.630342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:08.816916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:08.11365Z","time spent":"703.177101ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:08.817717Z","caller":"traceutil/trace.go:171","msg":"trace[1108272424] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:573; }","duration":"210.572794ms","start":"2026-04-16T02:55:08.607092Z","end":"2026-04-16T02:55:08.817665Z","steps":["trace[1108272424] 'read index received' (duration: 103.322533ms)","trace[1108272424] 'applied index is now lower than readState.Index' (duration: 107.249171ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:08.818312Z","caller":"traceutil/trace.go:171","msg":"trace[300836758] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"696.653183ms","start":"2026-04-16T02:55:08.121634Z","end":"2026-04-16T02:55:08.818288Z","steps":["trace[300836758] 'process raft request' (duration: 689.556427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:08.818403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:08.121615Z","time spent":"696.740864ms","remote":"127.0.0.1:49664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:08.818595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.491168ms","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-04-16T02:55:08.818642Z","caller":"traceutil/trace.go:171","msg":"trace[212219588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"211.60768ms","start":"2026-04-16T02:55:08.607024Z","end":"2026-04-16T02:55:08.818631Z","steps":["trace[212219588] 'agreement among raft nodes before linearized reading' (duration: 211.512908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:08.818824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.984213ms","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-04-16T02:55:08.818856Z","caller":"traceutil/trace.go:171","msg":"trace[394881131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"163.120306ms","start":"2026-04-16T02:55:08.655727Z","end":"2026-04-16T02:55:08.818847Z","steps":["trace[394881131] 'agreement among raft nodes before linearized reading' (duration: 163.061325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:08.819499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.253908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T02:55:08.819538Z","caller":"traceutil/trace.go:171","msg":"trace[1599394057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"153.37067ms","start":"2026-04-16T02:55:08.666157Z","end":"2026-04-16T02:55:08.819528Z","steps":["trace[1599394057] 'agreement among raft nodes before linearized reading' (duration: 153.304789ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:09.833537Z","caller":"traceutil/trace.go:171","msg":"trace[843922723] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"113.30513ms","start":"2026-04-16T02:55:09.720212Z","end":"2026-04-16T02:55:09.833517Z","steps":["trace[843922723] 'process raft request' (duration: 113.044306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:12.536286Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611846327331797,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T02:55:12.580768Z","caller":"traceutil/trace.go:171","msg":"trace[47289174] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"544.644124ms","start":"2026-04-16T02:55:12.036068Z","end":"2026-04-16T02:55:12.580712Z","steps":["trace[47289174] 'read index received' (duration: 542.815853ms)","trace[47289174] 'applied index is now lower than readState.Index' (duration: 1.824171ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:12.58089Z","caller":"traceutil/trace.go:171","msg":"trace[83062729] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"924.553994ms","start":"2026-04-16T02:55:11.656318Z","end":"2026-04-16T02:55:12.580872Z","steps":["trace[83062729] 'process raft request' (duration: 923.16454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:12.581302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:11.65629Z","time spent":"924.87237ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T02:55:12.58129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.189153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T02:55:12.581638Z","caller":"traceutil/trace.go:171","msg":"trace[957612955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"545.59377ms","start":"2026-04-16T02:55:12.036012Z","end":"2026-04-16T02:55:12.581606Z","steps":["trace[957612955] 'agreement among raft nodes before linearized reading' (duration: 544.949519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:12.581723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:12.035992Z","time spent":"545.721152ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T02:55:15.816288Z","caller":"traceutil/trace.go:171","msg":"trace[909126081] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"710.799569ms","start":"2026-04-16T02:55:15.105419Z","end":"2026-04-16T02:55:15.816218Z","steps":["trace[909126081] 'process raft request' (duration: 710.133867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:15.816571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:15.105374Z","time spent":"711.107994ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:16.963732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.935842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:16.963881Z","caller":"traceutil/trace.go:171","msg":"trace[1603359994] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.088913088s","start":"2026-04-16T02:55:15.874949Z","end":"2026-04-16T02:55:16.963862Z","steps":["trace[1603359994] 'process raft request' (duration: 256.531027ms)","trace[1603359994] 'compare' (duration: 831.611926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:16.963941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:15.874928Z","time spent":"1.088988659s","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T02:55:17.099848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611846327331850,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T02:55:17.248933Z","caller":"traceutil/trace.go:171","msg":"trace[1986441356] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"730.877249ms","start":"2026-04-16T02:55:16.518037Z","end":"2026-04-16T02:55:17.248915Z","steps":["trace[1986441356] 'process raft request' (duration: 730.755547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.249053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.518001Z","time spent":"730.993722ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:17.965814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.979221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:17.965969Z","caller":"traceutil/trace.go:171","msg":"trace[1582062431] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"1.366568198s","start":"2026-04-16T02:55:16.599373Z","end":"2026-04-16T02:55:17.965941Z","steps":["trace[1582062431] 'read index received' (duration: 649.655688ms)","trace[1582062431] 'applied index is now lower than readState.Index' (duration: 716.90976ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:17.966122Z","caller":"traceutil/trace.go:171","msg":"trace[1360183822] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.118239817s","start":"2026-04-16T02:55:16.847861Z","end":"2026-04-16T02:55:17.966101Z","steps":["trace[1360183822] 'process raft request' (duration: 502.702625ms)","trace[1360183822] 'compare' (duration: 614.655295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:17.966232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.847761Z","time spent":"1.118418s","remote":"127.0.0.1:49810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4538,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:17.966291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227951385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:17.966342Z","caller":"traceutil/trace.go:171","msg":"trace[1312369496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"1.228065747s","start":"2026-04-16T02:55:16.738262Z","end":"2026-04-16T02:55:17.966328Z","steps":["trace[1312369496] 'agreement among raft nodes before linearized reading' (duration: 1.227965345s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.966382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.738225Z","time spent":"1.228148289s","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T02:55:17.96654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.47453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T02:55:17.966613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101052333s","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-04-16T02:55:17.966649Z","caller":"traceutil/trace.go:171","msg":"trace[1286238722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.101092294s","start":"2026-04-16T02:55:16.865547Z","end":"2026-04-16T02:55:17.966639Z","steps":["trace[1286238722] 'agreement among raft nodes before linearized reading' (duration: 1.100991762s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.966658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103783524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T02:55:17.96668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.86551Z","time spent":"1.101162415s","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T02:55:17.966614Z","caller":"traceutil/trace.go:171","msg":"trace[1791943940] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:625; }","duration":"989.606982ms","start":"2026-04-16T02:55:16.976991Z","end":"2026-04-16T02:55:17.966598Z","steps":["trace[1791943940] 'agreement among raft nodes before linearized reading' (duration: 989.50461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.966673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367272331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T02:55:17.96671Z","caller":"traceutil/trace.go:171","msg":"trace[491242465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"1.103881806s","start":"2026-04-16T02:55:16.862812Z","end":"2026-04-16T02:55:17.966694Z","steps":["trace[491242465] 'agreement among raft nodes before linearized reading' (duration: 1.103730703s)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:17.966753Z","caller":"traceutil/trace.go:171","msg":"trace[105458784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"1.367436645s","start":"2026-04-16T02:55:16.599297Z","end":"2026-04-16T02:55:17.966734Z","steps":["trace[105458784] 'agreement among raft nodes before linearized reading' (duration: 1.36717427s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.966543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096427736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-16T02:55:17.966764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.976962Z","time spent":"989.758326ms","remote":"127.0.0.1:50134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-04-16T02:55:17.966794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.599276Z","time spent":"1.367511866s","remote":"127.0.0.1:49874","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-04-16T02:55:17.966832Z","caller":"traceutil/trace.go:171","msg":"trace[472853254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"1.096684721s","start":"2026-04-16T02:55:16.870101Z","end":"2026-04-16T02:55:17.966786Z","steps":["trace[472853254] 'agreement among raft nodes before linearized reading' (duration: 1.096246993s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.966756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.862793Z","time spent":"1.103951018s","remote":"127.0.0.1:49874","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-04-16T02:55:17.966881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.870064Z","time spent":"1.096804924s","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T02:55:18.361791Z","caller":"traceutil/trace.go:171","msg":"trace[1965281638] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"390.205355ms","start":"2026-04-16T02:55:17.971533Z","end":"2026-04-16T02:55:18.361738Z","steps":["trace[1965281638] 'process raft request' (duration: 389.96967ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:18.361836Z","caller":"traceutil/trace.go:171","msg":"trace[1897918466] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"390.45584ms","start":"2026-04-16T02:55:17.971344Z","end":"2026-04-16T02:55:18.3618Z","steps":["trace[1897918466] 'process raft request' (duration: 341.109865ms)","trace[1897918466] 'compare' (duration: 48.808144ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:18.361715Z","caller":"traceutil/trace.go:171","msg":"trace[303224715] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:644; }","duration":"385.964493ms","start":"2026-04-16T02:55:17.975714Z","end":"2026-04-16T02:55:18.361679Z","steps":["trace[303224715] 'read index received' (duration: 336.979336ms)","trace[303224715] 'applied index is now lower than readState.Index' (duration: 48.983037ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:18.362032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:17.971312Z","time spent":"390.606533ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:18.362034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:17.97152Z","time spent":"390.380059ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:18.36207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.31446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:18.362124Z","caller":"traceutil/trace.go:171","msg":"trace[978554425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"386.432662ms","start":"2026-04-16T02:55:17.975674Z","end":"2026-04-16T02:55:18.362106Z","steps":["trace[978554425] 'agreement among raft nodes before linearized reading' (duration: 386.305289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:18.362189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:17.975663Z","time spent":"386.512314ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T02:55:18.362167Z","caller":"traceutil/trace.go:171","msg":"trace[1130976209] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"389.498492ms","start":"2026-04-16T02:55:17.972641Z","end":"2026-04-16T02:55:18.36214Z","steps":["trace[1130976209] 'process raft request' (duration: 388.92209ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:18.362213Z","caller":"traceutil/trace.go:171","msg":"trace[1156424379] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"387.648426ms","start":"2026-04-16T02:55:17.974551Z","end":"2026-04-16T02:55:18.362199Z","steps":["trace[1156424379] 'process raft request' (duration: 387.078674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:18.362282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:17.974537Z","time spent":"387.708797ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:18.362322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:17.972621Z","time spent":"389.623104ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:19.099637Z","caller":"traceutil/trace.go:171","msg":"trace[1715612586] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"108.613429ms","start":"2026-04-16T02:55:18.990994Z","end":"2026-04-16T02:55:19.099608Z","steps":["trace[1715612586] 'process raft request' (duration: 103.809822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:34.975833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.802942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:34.975987Z","caller":"traceutil/trace.go:171","msg":"trace[151270002] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"454.991803ms","start":"2026-04-16T02:55:34.520983Z","end":"2026-04-16T02:55:34.975975Z","steps":["trace[151270002] 'read index received' (duration: 32.251µs)","trace[151270002] 'applied index is now lower than readState.Index' (duration: 454.958662ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:34.975998Z","caller":"traceutil/trace.go:171","msg":"trace[677797151] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"490.140909ms","start":"2026-04-16T02:55:34.485846Z","end":"2026-04-16T02:55:34.975987Z","steps":["trace[677797151] 'compare' (duration: 481.721159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:34.976086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:34.485822Z","time spent":"490.229571ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:34.976168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.189698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T02:55:34.976236Z","caller":"traceutil/trace.go:171","msg":"trace[1405031065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"455.290691ms","start":"2026-04-16T02:55:34.520937Z","end":"2026-04-16T02:55:34.976228Z","steps":["trace[1405031065] 'agreement among raft nodes before linearized reading' (duration: 455.130877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:34.976281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:34.520916Z","time spent":"455.358162ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T02:55:35.477128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611846327332247,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T02:55:35.767862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.24712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:35.768226Z","caller":"traceutil/trace.go:171","msg":"trace[264057874] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:816; }","duration":"792.178599ms","start":"2026-04-16T02:55:34.976023Z","end":"2026-04-16T02:55:35.768202Z","steps":["trace[264057874] 'read index received' (duration: 645.504797ms)","trace[264057874] 'applied index is now lower than readState.Index' (duration: 146.672602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:35.768493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227479739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T02:55:35.768902Z","caller":"traceutil/trace.go:171","msg":"trace[1820233147] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"819.651575ms","start":"2026-04-16T02:55:34.949189Z","end":"2026-04-16T02:55:35.768841Z","steps":["trace[1820233147] 'process raft request' (duration: 672.323426ms)","trace[1820233147] 'compare' (duration: 145.617343ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:35.769047Z","caller":"traceutil/trace.go:171","msg":"trace[740126861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"1.228021753s","start":"2026-04-16T02:55:34.540958Z","end":"2026-04-16T02:55:35.76898Z","steps":["trace[740126861] 'agreement among raft nodes before linearized reading' (duration: 1.227356176s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:35.768812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209784648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-16T02:55:35.769229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:34.949163Z","time spent":"819.875871ms","remote":"127.0.0.1:49862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:35.769239Z","caller":"traceutil/trace.go:171","msg":"trace[778550127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.21023123s","start":"2026-04-16T02:55:34.558959Z","end":"2026-04-16T02:55:35.76919Z","steps":["trace[778550127] 'agreement among raft nodes before linearized reading' (duration: 1.209766458s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:35.769239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:34.540943Z","time spent":"1.228232578s","remote":"127.0.0.1:49874","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-04-16T02:55:35.769316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:34.558935Z","time spent":"1.210361934s","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T02:55:35.768891Z","caller":"traceutil/trace.go:171","msg":"trace[1400308961] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"789.015787ms","start":"2026-04-16T02:55:34.979844Z","end":"2026-04-16T02:55:35.76886Z","steps":["trace[1400308961] 'process raft request' (duration: 788.274908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:35.769548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:34.979818Z","time spent":"789.693594ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:35.768801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029246535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:35.769677Z","caller":"traceutil/trace.go:171","msg":"trace[533096814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"1.030166909s","start":"2026-04-16T02:55:34.739496Z","end":"2026-04-16T02:55:35.769663Z","steps":["trace[533096814] 'agreement among raft nodes before linearized reading' (duration: 1.029215804s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:35.76972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:34.73948Z","time spent":"1.030230531s","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T02:55:42.990661Z","caller":"traceutil/trace.go:171","msg":"trace[30552923] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"251.431614ms","start":"2026-04-16T02:55:42.739191Z","end":"2026-04-16T02:55:42.990623Z","steps":["trace[30552923] 'read index received' (duration: 251.205168ms)","trace[30552923] 'applied index is now lower than readState.Index' (duration: 225.166µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:42.990704Z","caller":"traceutil/trace.go:171","msg":"trace[1234051896] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"367.934092ms","start":"2026-04-16T02:55:42.622734Z","end":"2026-04-16T02:55:42.990668Z","steps":["trace[1234051896] 'process raft request' (duration: 367.701065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:42.990904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.66642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:42.991018Z","caller":"traceutil/trace.go:171","msg":"trace[943513576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:862; }","duration":"251.866697ms","start":"2026-04-16T02:55:42.73912Z","end":"2026-04-16T02:55:42.990986Z","steps":["trace[943513576] 'agreement among raft nodes before linearized reading' (duration: 251.64971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:42.991127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:42.62271Z","time spent":"368.075386ms","remote":"127.0.0.1:49810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4971,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:43.23485Z","caller":"traceutil/trace.go:171","msg":"trace[1960761122] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"237.232828ms","start":"2026-04-16T02:55:42.997568Z","end":"2026-04-16T02:55:43.234801Z","steps":["trace[1960761122] 'process raft request' (duration: 212.027468ms)","trace[1960761122] 'compare' (duration: 24.926582ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:43.235054Z","caller":"traceutil/trace.go:171","msg":"trace[901309935] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"234.398116ms","start":"2026-04-16T02:55:43.000626Z","end":"2026-04-16T02:55:43.235024Z","steps":["trace[901309935] 'process raft request' (duration: 234.084687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:43.235171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.071891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:43.235353Z","caller":"traceutil/trace.go:171","msg":"trace[1652104165] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:865; }","duration":"221.324018ms","start":"2026-04-16T02:55:43.014015Z","end":"2026-04-16T02:55:43.235339Z","steps":["trace[1652104165] 'agreement among raft nodes before linearized reading' (duration: 221.074471ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:43.235045Z","caller":"traceutil/trace.go:171","msg":"trace[961460015] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:887; }","duration":"220.931917ms","start":"2026-04-16T02:55:43.014062Z","end":"2026-04-16T02:55:43.234994Z","steps":["trace[961460015] 'read index received' (duration: 17.330462ms)","trace[961460015] 'applied index is now lower than readState.Index' (duration: 203.599575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:43.247413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.056698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T02:55:43.247636Z","caller":"traceutil/trace.go:171","msg":"trace[242264690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:865; }","duration":"233.312486ms","start":"2026-04-16T02:55:43.014301Z","end":"2026-04-16T02:55:43.247613Z","steps":["trace[242264690] 'agreement among raft nodes before linearized reading' (duration: 232.959516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:43.247468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.156914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-7bk8b\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-04-16T02:55:43.247856Z","caller":"traceutil/trace.go:171","msg":"trace[1142018762] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-7bk8b; range_end:; response_count:1; response_revision:865; }","duration":"211.579426ms","start":"2026-04-16T02:55:43.036238Z","end":"2026-04-16T02:55:43.247818Z","steps":["trace[1142018762] 'agreement among raft nodes before linearized reading' (duration: 211.03487ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:43.416978Z","caller":"traceutil/trace.go:171","msg":"trace[1264121842] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"161.462235ms","start":"2026-04-16T02:55:43.255493Z","end":"2026-04-16T02:55:43.416955Z","steps":["trace[1264121842] 'process raft request' (duration: 161.399573ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:43.416983Z","caller":"traceutil/trace.go:171","msg":"trace[1855025484] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"166.066648ms","start":"2026-04-16T02:55:43.250894Z","end":"2026-04-16T02:55:43.416961Z","steps":["trace[1855025484] 'process raft request' (duration: 159.844188ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:43.428029Z","caller":"traceutil/trace.go:171","msg":"trace[527190654] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:892; }","duration":"112.134627ms","start":"2026-04-16T02:55:43.315882Z","end":"2026-04-16T02:55:43.428017Z","steps":["trace[527190654] 'read index received' (duration: 94.883508ms)","trace[527190654] 'applied index is now lower than readState.Index' (duration: 17.249289ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:43.428057Z","caller":"traceutil/trace.go:171","msg":"trace[1180017670] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"164.231815ms","start":"2026-04-16T02:55:43.263802Z","end":"2026-04-16T02:55:43.428034Z","steps":["trace[1180017670] 'process raft request' (duration: 164.132972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:43.428262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.345783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T02:55:43.428378Z","caller":"traceutil/trace.go:171","msg":"trace[1844270893] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:869; }","duration":"112.545059ms","start":"2026-04-16T02:55:43.315809Z","end":"2026-04-16T02:55:43.428354Z","steps":["trace[1844270893] 'agreement among raft nodes before linearized reading' (duration: 112.263031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:43.428721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.442395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-16T02:55:43.428759Z","caller":"traceutil/trace.go:171","msg":"trace[1693879630] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:869; }","duration":"112.541489ms","start":"2026-04-16T02:55:43.316209Z","end":"2026-04-16T02:55:43.42875Z","steps":["trace[1693879630] 'agreement among raft nodes before linearized reading' (duration: 111.89211ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:43.609454Z","caller":"traceutil/trace.go:171","msg":"trace[534401190] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"174.06898ms","start":"2026-04-16T02:55:43.435352Z","end":"2026-04-16T02:55:43.609421Z","steps":["trace[534401190] 'process raft request' (duration: 130.282742ms)","trace[534401190] 'compare' (duration: 43.603012ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:44.05193Z","caller":"traceutil/trace.go:171","msg":"trace[1071754114] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"158.533215ms","start":"2026-04-16T02:55:43.89334Z","end":"2026-04-16T02:55:44.051873Z","steps":["trace[1071754114] 'process raft request' (duration: 96.799172ms)","trace[1071754114] 'compare' (duration: 61.460795ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:44.052025Z","caller":"traceutil/trace.go:171","msg":"trace[1734201440] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"156.709653ms","start":"2026-04-16T02:55:43.89529Z","end":"2026-04-16T02:55:44.052Z","steps":["trace[1734201440] 'process raft request' (duration: 156.60696ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:44.052122Z","caller":"traceutil/trace.go:171","msg":"trace[1117742635] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"156.813806ms","start":"2026-04-16T02:55:43.895269Z","end":"2026-04-16T02:55:44.052083Z","steps":["trace[1117742635] 'process raft request' (duration: 156.558949ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:44.052516Z","caller":"traceutil/trace.go:171","msg":"trace[1823654290] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"158.529095ms","start":"2026-04-16T02:55:43.893415Z","end":"2026-04-16T02:55:44.051944Z","steps":["trace[1823654290] 'process raft request' (duration: 158.36371ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:48.208894Z","caller":"traceutil/trace.go:171","msg":"trace[1158109953] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"134.85725ms","start":"2026-04-16T02:55:48.073995Z","end":"2026-04-16T02:55:48.208852Z","steps":["trace[1158109953] 'process raft request' (duration: 103.871568ms)","trace[1158109953] 'compare' (duration: 30.645351ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:48.209187Z","caller":"traceutil/trace.go:171","msg":"trace[1798450748] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"133.374744ms","start":"2026-04-16T02:55:48.075784Z","end":"2026-04-16T02:55:48.209159Z","steps":["trace[1798450748] 'process raft request' (duration: 133.016503ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:48.209833Z","caller":"traceutil/trace.go:171","msg":"trace[960625945] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"103.605569ms","start":"2026-04-16T02:55:48.106196Z","end":"2026-04-16T02:55:48.209801Z","steps":["trace[960625945] 'process raft request' (duration: 102.733923ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:48.456367Z","caller":"traceutil/trace.go:171","msg":"trace[1352134732] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"115.902673ms","start":"2026-04-16T02:55:48.340434Z","end":"2026-04-16T02:55:48.456336Z","steps":["trace[1352134732] 'process raft request' (duration: 53.515457ms)","trace[1352134732] 'compare' (duration: 62.239482ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:50.761061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.835571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-16T02:55:50.761166Z","caller":"traceutil/trace.go:171","msg":"trace[712105729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"546.962565ms","start":"2026-04-16T02:55:50.214186Z","end":"2026-04-16T02:55:50.761149Z","steps":["trace[712105729] 'range keys from in-memory index tree' (duration: 546.726958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:50.761196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:50.214181Z","time spent":"547.008487ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T02:55:50.761197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.640576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-16T02:55:50.761245Z","caller":"traceutil/trace.go:171","msg":"trace[72410976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"547.719888ms","start":"2026-04-16T02:55:50.213507Z","end":"2026-04-16T02:55:50.761227Z","steps":["trace[72410976] 'range keys from in-memory index tree' (duration: 547.543123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:50.761067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.755739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T02:55:50.761896Z","caller":"traceutil/trace.go:171","msg":"trace[2116439307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"548.645558ms","start":"2026-04-16T02:55:50.213239Z","end":"2026-04-16T02:55:50.761884Z","steps":["trace[2116439307] 'range keys from in-memory index tree' (duration: 547.549363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:50.761931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:50.213216Z","time spent":"548.705589ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T02:55:50.761927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:50.213484Z","time spent":"548.265205ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T02:55:50.7616Z","caller":"traceutil/trace.go:171","msg":"trace[781302857] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"297.583509ms","start":"2026-04-16T02:55:50.464008Z","end":"2026-04-16T02:55:50.761591Z","steps":["trace[781302857] 'process raft request' (duration: 293.496362ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:50.992216Z","caller":"traceutil/trace.go:171","msg":"trace[1591905548] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"224.846207ms","start":"2026-04-16T02:55:50.767303Z","end":"2026-04-16T02:55:50.992149Z","steps":["trace[1591905548] 'read index received' (duration: 223.186126ms)","trace[1591905548] 'applied index is now lower than readState.Index' (duration: 1.658301ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:50.992306Z","caller":"traceutil/trace.go:171","msg":"trace[1151852908] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"224.990622ms","start":"2026-04-16T02:55:50.767277Z","end":"2026-04-16T02:55:50.992267Z","steps":["trace[1151852908] 'process raft request' (duration: 223.197746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:50.992366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.032954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:50.99244Z","caller":"traceutil/trace.go:171","msg":"trace[960927580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"225.165828ms","start":"2026-04-16T02:55:50.767262Z","end":"2026-04-16T02:55:50.992427Z","steps":["trace[960927580] 'agreement among raft nodes before linearized reading' (duration: 225.036824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:51.26904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.932323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:51.269295Z","caller":"traceutil/trace.go:171","msg":"trace[1288333839] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"501.720757ms","start":"2026-04-16T02:55:50.767555Z","end":"2026-04-16T02:55:51.269276Z","steps":["trace[1288333839] 'process raft request' (duration: 501.616954ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:51.269334Z","caller":"traceutil/trace.go:171","msg":"trace[1873965144] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"501.80776ms","start":"2026-04-16T02:55:50.767489Z","end":"2026-04-16T02:55:51.269297Z","steps":["trace[1873965144] 'process raft request' (duration: 398.521166ms)","trace[1873965144] 'compare' (duration: 102.794588ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:51.269373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:50.767539Z","time spent":"501.800339ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:51.2695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:50.767455Z","time spent":"501.955434ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:54.091996Z","caller":"traceutil/trace.go:171","msg":"trace[1191321935] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"118.069259ms","start":"2026-04-16T02:55:53.973901Z","end":"2026-04-16T02:55:54.09197Z","steps":["trace[1191321935] 'read index received' (duration: 117.888253ms)","trace[1191321935] 'applied index is now lower than readState.Index' (duration: 158.835µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:54.092187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.250125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T02:55:54.092283Z","caller":"traceutil/trace.go:171","msg":"trace[933615552] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:946; }","duration":"118.390089ms","start":"2026-04-16T02:55:53.973871Z","end":"2026-04-16T02:55:54.092261Z","steps":["trace[933615552] 'agreement among raft nodes before linearized reading' (duration: 118.188683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:54.092212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.559471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:54.09261Z","caller":"traceutil/trace.go:171","msg":"trace[1907518299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"117.020236ms","start":"2026-04-16T02:55:53.975577Z","end":"2026-04-16T02:55:54.092597Z","steps":["trace[1907518299] 'agreement among raft nodes before linearized reading' (duration: 116.555121ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:56:07.536017Z","caller":"traceutil/trace.go:171","msg":"trace[1493706319] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"183.877706ms","start":"2026-04-16T02:56:07.352107Z","end":"2026-04-16T02:56:07.535984Z","steps":["trace[1493706319] 'process raft request' (duration: 159.66304ms)","trace[1493706319] 'compare' (duration: 24.0258ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:56:15.034449Z","caller":"traceutil/trace.go:171","msg":"trace[2042253522] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"201.290636ms","start":"2026-04-16T02:56:14.833127Z","end":"2026-04-16T02:56:15.034418Z","steps":["trace[2042253522] 'read index received' (duration: 201.12097ms)","trace[2042253522] 'applied index is now lower than readState.Index' (duration: 167.886µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:56:15.034684Z","caller":"traceutil/trace.go:171","msg":"trace[1991606727] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"281.375165ms","start":"2026-04-16T02:56:14.753256Z","end":"2026-04-16T02:56:15.034631Z","steps":["trace[1991606727] 'process raft request' (duration: 280.988431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:15.035098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.467903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T02:56:15.035146Z","caller":"traceutil/trace.go:171","msg":"trace[1481160601] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1019; }","duration":"202.087795ms","start":"2026-04-16T02:56:14.833046Z","end":"2026-04-16T02:56:15.035134Z","steps":["trace[1481160601] 'agreement among raft nodes before linearized reading' (duration: 201.497324ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:56:15.104003Z","caller":"traceutil/trace.go:171","msg":"trace[755021621] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"201.59611ms","start":"2026-04-16T02:56:14.902365Z","end":"2026-04-16T02:56:15.103961Z","steps":["trace[755021621] 'process raft request' (duration: 193.787755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:36.229309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.160537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:56:36.229407Z","caller":"traceutil/trace.go:171","msg":"trace[2073617212] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1088; }","duration":"539.296402ms","start":"2026-04-16T02:56:35.690085Z","end":"2026-04-16T02:56:36.229382Z","steps":["trace[2073617212] 'count revisions from in-memory index tree' (duration: 539.098113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:36.229448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:35.690076Z","time spent":"539.362765ms","remote":"127.0.0.1:54076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-16T02:56:36.229422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.058335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:56:36.229497Z","caller":"traceutil/trace.go:171","msg":"trace[15434354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"493.193841ms","start":"2026-04-16T02:56:35.736284Z","end":"2026-04-16T02:56:36.229478Z","steps":["trace[15434354] 'range keys from in-memory index tree' (duration: 492.941437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:36.229537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:35.736259Z","time spent":"493.269373ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T02:56:36.229577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.105566ms","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-04-16T02:56:36.229631Z","caller":"traceutil/trace.go:171","msg":"trace[851690568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1088; }","duration":"557.192158ms","start":"2026-04-16T02:56:35.672422Z","end":"2026-04-16T02:56:36.229614Z","steps":["trace[851690568] 'range keys from in-memory index tree' (duration: 556.949503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:36.22963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.1054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T02:56:36.229616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.893032ms","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-04-16T02:56:36.229665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:35.672402Z","time spent":"557.255421ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T02:56:36.229674Z","caller":"traceutil/trace.go:171","msg":"trace[1080095164] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1088; }","duration":"550.205459ms","start":"2026-04-16T02:56:35.679457Z","end":"2026-04-16T02:56:36.229662Z","steps":["trace[1080095164] 'count revisions from in-memory index tree' (duration: 549.952816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:36.229697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:35.679432Z","time spent":"550.258284ms","remote":"127.0.0.1:49734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-04-16T02:56:36.229695Z","caller":"traceutil/trace.go:171","msg":"trace[876129864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"413.017339ms","start":"2026-04-16T02:56:35.816657Z","end":"2026-04-16T02:56:36.229674Z","steps":["trace[876129864] 'range keys from in-memory index tree' (duration: 412.698292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:36.229738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:35.816637Z","time spent":"413.092591ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T02:56:44.734387Z","caller":"traceutil/trace.go:171","msg":"trace[1301297389] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1154; }","duration":"363.714236ms","start":"2026-04-16T02:56:44.370644Z","end":"2026-04-16T02:56:44.734358Z","steps":["trace[1301297389] 'read index received' (duration: 363.456953ms)","trace[1301297389] 'applied index is now lower than readState.Index' (duration: 256.103µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:56:44.734783Z","caller":"traceutil/trace.go:171","msg":"trace[327297550] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"459.403632ms","start":"2026-04-16T02:56:44.275366Z","end":"2026-04-16T02:56:44.73477Z","steps":["trace[327297550] 'process raft request' (duration: 458.766221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:44.734828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.998773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T02:56:44.734831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.164088ms","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-04-16T02:56:44.734914Z","caller":"traceutil/trace.go:171","msg":"trace[1207662907] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1115; }","duration":"231.166532ms","start":"2026-04-16T02:56:44.503729Z","end":"2026-04-16T02:56:44.734895Z","steps":["trace[1207662907] 'agreement among raft nodes before linearized reading' (duration: 231.007024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:44.734919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:44.275352Z","time spent":"459.479896ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:56:44.734932Z","caller":"traceutil/trace.go:171","msg":"trace[1294518994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1115; }","duration":"364.324965ms","start":"2026-04-16T02:56:44.370584Z","end":"2026-04-16T02:56:44.734909Z","steps":["trace[1294518994] 'agreement among raft nodes before linearized reading' (duration: 364.01011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:44.734988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:44.370557Z","time spent":"364.420431ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T02:56:44.735456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.803477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T02:56:44.735503Z","caller":"traceutil/trace.go:171","msg":"trace[1917005428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"289.895171ms","start":"2026-04-16T02:56:44.445596Z","end":"2026-04-16T02:56:44.735491Z","steps":["trace[1917005428] 'agreement among raft nodes before linearized reading' (duration: 289.03843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:45.586301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.213305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:56:45.586489Z","caller":"traceutil/trace.go:171","msg":"trace[1011787249] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"846.7057ms","start":"2026-04-16T02:56:44.739744Z","end":"2026-04-16T02:56:45.58645Z","steps":["trace[1011787249] 'process raft request' (duration: 535.266394ms)","trace[1011787249] 'compare' (duration: 311.128021ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:56:45.58655Z","caller":"traceutil/trace.go:171","msg":"trace[1157539012] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1155; }","duration":"409.669523ms","start":"2026-04-16T02:56:45.176869Z","end":"2026-04-16T02:56:45.586538Z","steps":["trace[1157539012] 'read index received' (duration: 98.162114ms)","trace[1157539012] 'applied index is now lower than readState.Index' (duration: 311.506259ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:56:45.586567Z","caller":"traceutil/trace.go:171","msg":"trace[1826007531] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"845.437529ms","start":"2026-04-16T02:56:44.741122Z","end":"2026-04-16T02:56:45.586559Z","steps":["trace[1826007531] 'process raft request' (duration: 845.260561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:45.586647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.783419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T02:56:45.586673Z","caller":"traceutil/trace.go:171","msg":"trace[99767414] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1119; }","duration":"409.845391ms","start":"2026-04-16T02:56:45.176818Z","end":"2026-04-16T02:56:45.586664Z","steps":["trace[99767414] 'agreement among raft nodes before linearized reading' (duration: 409.783068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:45.586696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:45.176795Z","time spent":"409.893355ms","remote":"127.0.0.1:50126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-16T02:56:45.586697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:44.741107Z","time spent":"845.495413ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:56:45.586696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:44.739721Z","time spent":"846.855838ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:56:45.586492Z","caller":"traceutil/trace.go:171","msg":"trace[736218224] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"369.20583ms","start":"2026-04-16T02:56:45.217262Z","end":"2026-04-16T02:56:45.586468Z","steps":["trace[736218224] 'process raft request' (duration: 369.150917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:45.586858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.450586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T02:56:45.586874Z","caller":"traceutil/trace.go:171","msg":"trace[470786032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1119; }","duration":"170.508978ms","start":"2026-04-16T02:56:45.41636Z","end":"2026-04-16T02:56:45.586869Z","steps":["trace[470786032] 'agreement among raft nodes before linearized reading' (duration: 170.435605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:56:45.586973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:56:45.217236Z","time spent":"369.61928ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:57:05.855872Z","caller":"traceutil/trace.go:171","msg":"trace[1176662674] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1227; }","duration":"120.839093ms","start":"2026-04-16T02:57:05.735Z","end":"2026-04-16T02:57:05.855839Z","steps":["trace[1176662674] 'read index received' (duration: 119.614044ms)","trace[1176662674] 'applied index is now lower than readState.Index' (duration: 1.223829ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:05.855965Z","caller":"traceutil/trace.go:171","msg":"trace[757893418] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"132.054112ms","start":"2026-04-16T02:57:05.723888Z","end":"2026-04-16T02:57:05.855942Z","steps":["trace[757893418] 'process raft request' (duration: 130.741638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:05.856075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.049573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:05.856117Z","caller":"traceutil/trace.go:171","msg":"trace[1804012990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1184; }","duration":"121.163248ms","start":"2026-04-16T02:57:05.734941Z","end":"2026-04-16T02:57:05.856104Z","steps":["trace[1804012990] 'agreement among raft nodes before linearized reading' (duration: 121.059303ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:08.05367Z","caller":"traceutil/trace.go:171","msg":"trace[222498613] linearizableReadLoop","detail":"{readStateIndex:1234; appliedIndex:1233; }","duration":"194.063679ms","start":"2026-04-16T02:57:07.859562Z","end":"2026-04-16T02:57:08.053626Z","steps":["trace[222498613] 'read index received' (duration: 193.351695ms)","trace[222498613] 'applied index is now lower than readState.Index' (duration: 709.604µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:08.053886Z","caller":"traceutil/trace.go:171","msg":"trace[831852153] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"263.021841ms","start":"2026-04-16T02:57:07.79084Z","end":"2026-04-16T02:57:08.053861Z","steps":["trace[831852153] 'process raft request' (duration: 262.209322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:08.054546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.812897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-16T02:57:08.054558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.938701ms","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-04-16T02:57:08.054643Z","caller":"traceutil/trace.go:171","msg":"trace[536940633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1190; }","duration":"178.924542ms","start":"2026-04-16T02:57:07.875695Z","end":"2026-04-16T02:57:08.05462Z","steps":["trace[536940633] 'agreement among raft nodes before linearized reading' (duration: 178.754524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:08.054736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.951066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T02:57:08.054643Z","caller":"traceutil/trace.go:171","msg":"trace[1314122874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1190; }","duration":"195.100079ms","start":"2026-04-16T02:57:07.859527Z","end":"2026-04-16T02:57:08.054627Z","steps":["trace[1314122874] 'agreement among raft nodes before linearized reading' (duration: 194.370953ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:08.054809Z","caller":"traceutil/trace.go:171","msg":"trace[115166349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1190; }","duration":"188.065041ms","start":"2026-04-16T02:57:07.866722Z","end":"2026-04-16T02:57:08.054787Z","steps":["trace[115166349] 'agreement among raft nodes before linearized reading' (duration: 187.708084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:08.89299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.859528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:08.893143Z","caller":"traceutil/trace.go:171","msg":"trace[376715561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"154.075499ms","start":"2026-04-16T02:57:08.739039Z","end":"2026-04-16T02:57:08.893114Z","steps":["trace[376715561] 'range keys from in-memory index tree' (duration: 153.745993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:08.893512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.090121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:08.893994Z","caller":"traceutil/trace.go:171","msg":"trace[1377001072] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1193; }","duration":"199.70058ms","start":"2026-04-16T02:57:08.694255Z","end":"2026-04-16T02:57:08.893956Z","steps":["trace[1377001072] 'count revisions from in-memory index tree' (duration: 198.961514ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:09.226469Z","caller":"traceutil/trace.go:171","msg":"trace[1759609171] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"310.285421ms","start":"2026-04-16T02:57:08.916154Z","end":"2026-04-16T02:57:09.226439Z","steps":["trace[1759609171] 'process raft request' (duration: 310.108212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:09.226634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:08.916124Z","time spent":"310.429377ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:57:09.91523Z","caller":"traceutil/trace.go:171","msg":"trace[1706356463] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"123.770384ms","start":"2026-04-16T02:57:09.791434Z","end":"2026-04-16T02:57:09.915204Z","steps":["trace[1706356463] 'process raft request' (duration: 123.595646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:11.207648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.276316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:11.207784Z","caller":"traceutil/trace.go:171","msg":"trace[1684418185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1200; }","duration":"469.464305ms","start":"2026-04-16T02:57:10.738304Z","end":"2026-04-16T02:57:11.207769Z","steps":["trace[1684418185] 'range keys from in-memory index tree' (duration: 469.192441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:11.207836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:10.738283Z","time spent":"469.543609ms","remote":"127.0.0.1:49604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T02:57:11.207959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.787899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:11.208065Z","caller":"traceutil/trace.go:171","msg":"trace[1536643994] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1200; }","duration":"854.897555ms","start":"2026-04-16T02:57:10.353112Z","end":"2026-04-16T02:57:11.208009Z","steps":["trace[1536643994] 'count revisions from in-memory index tree' (duration: 854.701075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:11.20812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:10.353064Z","time spent":"855.040011ms","remote":"127.0.0.1:54134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-16T02:57:11.208224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.238572ms","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":"warn","ts":"2026-04-16T02:57:11.208274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.748487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T02:57:11.208309Z","caller":"traceutil/trace.go:171","msg":"trace[1122473394] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1200; }","duration":"374.388079ms","start":"2026-04-16T02:57:10.833901Z","end":"2026-04-16T02:57:11.208289Z","steps":["trace[1122473394] 'count revisions from in-memory index tree' (duration: 374.144837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:11.20836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:10.833879Z","time spent":"374.470233ms","remote":"127.0.0.1:49862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-04-16T02:57:11.208306Z","caller":"traceutil/trace.go:171","msg":"trace[752378851] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1200; }","duration":"485.880113ms","start":"2026-04-16T02:57:10.722416Z","end":"2026-04-16T02:57:11.208297Z","steps":["trace[752378851] 'count revisions from in-memory index tree' (duration: 485.627851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:11.208444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:10.722394Z","time spent":"486.006899ms","remote":"127.0.0.1:50110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-16T02:57:11.77907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611846327333205,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T02:57:11.96517Z","caller":"traceutil/trace.go:171","msg":"trace[1760086890] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"686.809181ms","start":"2026-04-16T02:57:11.278336Z","end":"2026-04-16T02:57:11.965146Z","steps":["trace[1760086890] 'read index received' (duration: 686.656494ms)","trace[1760086890] 'applied index is now lower than readState.Index' (duration: 151.457µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:11.965208Z","caller":"traceutil/trace.go:171","msg":"trace[814978453] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"728.582388ms","start":"2026-04-16T02:57:11.236599Z","end":"2026-04-16T02:57:11.965181Z","steps":["trace[814978453] 'process raft request' (duration: 728.355497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:11.965359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.004861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T02:57:11.965367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:11.236576Z","time spent":"728.712064ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:57:11.965394Z","caller":"traceutil/trace.go:171","msg":"trace[800312264] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1201; }","duration":"687.066544ms","start":"2026-04-16T02:57:11.278318Z","end":"2026-04-16T02:57:11.965385Z","steps":["trace[800312264] 'agreement among raft nodes before linearized reading' (duration: 686.942908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:11.965431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:11.278256Z","time spent":"687.165759ms","remote":"127.0.0.1:49944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-16T02:57:11.980269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.439644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:11.980342Z","caller":"traceutil/trace.go:171","msg":"trace[410942148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"241.539348ms","start":"2026-04-16T02:57:11.738785Z","end":"2026-04-16T02:57:11.980324Z","steps":["trace[410942148] 'agreement among raft nodes before linearized reading' (duration: 241.414922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:14.567558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611846327333225,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T02:57:14.607022Z","caller":"traceutil/trace.go:171","msg":"trace[2021753758] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"622.059633ms","start":"2026-04-16T02:57:13.984941Z","end":"2026-04-16T02:57:14.607Z","steps":["trace[2021753758] 'process raft request' (duration: 621.859013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:14.607288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:13.984916Z","time spent":"622.174858ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:57:14.651328Z","caller":"traceutil/trace.go:171","msg":"trace[1172417596] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"580.056866ms","start":"2026-04-16T02:57:14.071254Z","end":"2026-04-16T02:57:14.651311Z","steps":["trace[1172417596] 'process raft request' (duration: 579.93812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:14.651447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.993272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T02:57:14.651485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:14.071237Z","time spent":"580.170911ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:57:14.651505Z","caller":"traceutil/trace.go:171","msg":"trace[1025418730] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1209; }","duration":"656.106267ms","start":"2026-04-16T02:57:13.995384Z","end":"2026-04-16T02:57:14.65149Z","steps":["trace[1025418730] 'agreement among raft nodes before linearized reading' (duration: 655.96288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:14.651546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:13.99537Z","time spent":"656.165461ms","remote":"127.0.0.1:54096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-16T02:57:14.651641Z","caller":"traceutil/trace.go:171","msg":"trace[1644378564] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1252; }","duration":"584.73074ms","start":"2026-04-16T02:57:14.066518Z","end":"2026-04-16T02:57:14.651249Z","steps":["trace[1644378564] 'read index received' (duration: 540.37718ms)","trace[1644378564] 'applied index is now lower than readState.Index' (duration: 44.35235ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:57:14.651717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.249365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:14.651874Z","caller":"traceutil/trace.go:171","msg":"trace[1428541971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"676.437234ms","start":"2026-04-16T02:57:13.975388Z","end":"2026-04-16T02:57:14.651825Z","steps":["trace[1428541971] 'agreement among raft nodes before linearized reading' (duration: 676.146221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:14.651982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:13.975381Z","time spent":"676.585731ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T02:57:14.652052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.228502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-16T02:57:14.652055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.727408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T02:57:14.65209Z","caller":"traceutil/trace.go:171","msg":"trace[336467249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1209; }","duration":"465.323286ms","start":"2026-04-16T02:57:14.186755Z","end":"2026-04-16T02:57:14.652078Z","steps":["trace[336467249] 'agreement among raft nodes before linearized reading' (duration: 465.040222ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:14.652095Z","caller":"traceutil/trace.go:171","msg":"trace[1872844890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1209; }","duration":"418.803952ms","start":"2026-04-16T02:57:14.23328Z","end":"2026-04-16T02:57:14.652084Z","steps":["trace[1872844890] 'agreement among raft nodes before linearized reading' (duration: 418.697167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:14.652136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:14.233261Z","time spent":"418.862655ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T02:57:14.652127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:14.186738Z","time spent":"465.382099ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T02:57:14.652078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.307715ms","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-04-16T02:57:14.65231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.413056ms","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":"warn","ts":"2026-04-16T02:57:14.652319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.721439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-16T02:57:14.652347Z","caller":"traceutil/trace.go:171","msg":"trace[1553319311] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1209; }","duration":"558.509541ms","start":"2026-04-16T02:57:14.093827Z","end":"2026-04-16T02:57:14.652336Z","steps":["trace[1553319311] 'agreement among raft nodes before linearized reading' (duration: 557.890061ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:14.652363Z","caller":"traceutil/trace.go:171","msg":"trace[1996953757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"534.804713ms","start":"2026-04-16T02:57:14.117546Z","end":"2026-04-16T02:57:14.652351Z","steps":["trace[1996953757] 'agreement among raft nodes before linearized reading' (duration: 534.693427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:14.652378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:14.093797Z","time spent":"558.572874ms","remote":"127.0.0.1:54240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-16T02:57:14.652401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:14.117518Z","time spent":"534.873506ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T02:57:14.652323Z","caller":"traceutil/trace.go:171","msg":"trace[1735290755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1209; }","duration":"440.595698ms","start":"2026-04-16T02:57:14.211705Z","end":"2026-04-16T02:57:14.652301Z","steps":["trace[1735290755] 'agreement among raft nodes before linearized reading' (duration: 440.041282ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:14.652703Z","caller":"traceutil/trace.go:171","msg":"trace[734009986] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"584.52181ms","start":"2026-04-16T02:57:14.068164Z","end":"2026-04-16T02:57:14.652686Z","steps":["trace[734009986] 'process raft request' (duration: 582.665511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:14.652783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:14.068135Z","time spent":"584.606975ms","remote":"127.0.0.1:49640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":117,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:57:14.652752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:14.211691Z","time spent":"441.0504ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T02:57:14.758481Z","caller":"traceutil/trace.go:171","msg":"trace[1591552784] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"100.78587ms","start":"2026-04-16T02:57:14.657599Z","end":"2026-04-16T02:57:14.758385Z","steps":["trace[1591552784] 'process raft request' (duration: 100.563519ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:14.758733Z","caller":"traceutil/trace.go:171","msg":"trace[433184883] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"100.072936ms","start":"2026-04-16T02:57:14.658627Z","end":"2026-04-16T02:57:14.7587Z","steps":["trace[433184883] 'process raft request' (duration: 99.715339ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:14.759727Z","caller":"traceutil/trace.go:171","msg":"trace[484858387] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1255; }","duration":"100.828132ms","start":"2026-04-16T02:57:14.65888Z","end":"2026-04-16T02:57:14.759708Z","steps":["trace[484858387] 'read index received' (duration: 20.387309ms)","trace[484858387] 'applied index is now lower than readState.Index' (duration: 80.438803ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:14.759887Z","caller":"traceutil/trace.go:171","msg":"trace[11040369] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"100.815551ms","start":"2026-04-16T02:57:14.65901Z","end":"2026-04-16T02:57:14.759826Z","steps":["trace[11040369] 'process raft request' (duration: 99.636174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:14.760345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.435131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-16T02:57:14.760478Z","caller":"traceutil/trace.go:171","msg":"trace[274492602] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1213; }","duration":"101.598249ms","start":"2026-04-16T02:57:14.65886Z","end":"2026-04-16T02:57:14.760458Z","steps":["trace[274492602] 'agreement among raft nodes before linearized reading' (duration: 101.273024ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:19.010952Z","caller":"traceutil/trace.go:171","msg":"trace[280631852] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"166.435982ms","start":"2026-04-16T02:57:18.844444Z","end":"2026-04-16T02:57:19.01088Z","steps":["trace[280631852] 'process raft request' (duration: 108.222937ms)","trace[280631852] 'compare' (duration: 57.541033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:57:19.395714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.548996ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-04-16T02:57:30.953407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.024992ms","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-04-16T02:57:30.953526Z","caller":"traceutil/trace.go:171","msg":"trace[944486211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1261; }","duration":"146.221662ms","start":"2026-04-16T02:57:30.807277Z","end":"2026-04-16T02:57:30.953499Z","steps":["trace[944486211] 'range keys from in-memory index tree' (duration: 145.723368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:30.953686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.93531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T02:57:30.953808Z","caller":"traceutil/trace.go:171","msg":"trace[1175290443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1261; }","duration":"140.119828ms","start":"2026-04-16T02:57:30.813658Z","end":"2026-04-16T02:57:30.953778Z","steps":["trace[1175290443] 'range keys from in-memory index tree' (duration: 139.32187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:31.272999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.998204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:57:31.273238Z","caller":"traceutil/trace.go:171","msg":"trace[1969654925] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1311; }","duration":"290.895889ms","start":"2026-04-16T02:57:30.982323Z","end":"2026-04-16T02:57:31.273219Z","steps":["trace[1969654925] 'read index received' (duration: 100.649283ms)","trace[1969654925] 'applied index is now lower than readState.Index' (duration: 190.245286ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:31.273296Z","caller":"traceutil/trace.go:171","msg":"trace[584769132] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"312.98364ms","start":"2026-04-16T02:57:30.960283Z","end":"2026-04-16T02:57:31.273266Z","steps":["trace[584769132] 'process raft request' (duration: 122.629339ms)","trace[584769132] 'compare' (duration: 189.861517ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:31.273339Z","caller":"traceutil/trace.go:171","msg":"trace[956656039] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"312.989261ms","start":"2026-04-16T02:57:30.96034Z","end":"2026-04-16T02:57:31.27333Z","steps":["trace[956656039] 'process raft request' (duration: 312.800852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:31.273399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:30.960295Z","time spent":"313.069804ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:57:31.273442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:57:30.960258Z","time spent":"313.096916ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:57:31.273604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.273107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T02:57:31.273656Z","caller":"traceutil/trace.go:171","msg":"trace[2113023366] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1264; }","duration":"291.33418ms","start":"2026-04-16T02:57:30.982309Z","end":"2026-04-16T02:57:31.273643Z","steps":["trace[2113023366] 'agreement among raft nodes before linearized reading' (duration: 291.246226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:31.273632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.786164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T02:57:31.273776Z","caller":"traceutil/trace.go:171","msg":"trace[1616642962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"235.003735ms","start":"2026-04-16T02:57:31.038737Z","end":"2026-04-16T02:57:31.273741Z","steps":["trace[1616642962] 'agreement among raft nodes before linearized reading' (duration: 234.69093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:31.27392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.009707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-16T02:57:31.273958Z","caller":"traceutil/trace.go:171","msg":"trace[1991342814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"189.09518ms","start":"2026-04-16T02:57:31.084854Z","end":"2026-04-16T02:57:31.273949Z","steps":["trace[1991342814] 'agreement among raft nodes before linearized reading' (duration: 188.921552ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:31.449194Z","caller":"traceutil/trace.go:171","msg":"trace[2021076212] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1313; }","duration":"101.423741ms","start":"2026-04-16T02:57:31.347742Z","end":"2026-04-16T02:57:31.449166Z","steps":["trace[2021076212] 'read index received' (duration: 8.206965ms)","trace[2021076212] 'applied index is now lower than readState.Index' (duration: 93.215276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:57:31.449349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.582908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:31.449385Z","caller":"traceutil/trace.go:171","msg":"trace[2089282842] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1266; }","duration":"101.702494ms","start":"2026-04-16T02:57:31.34767Z","end":"2026-04-16T02:57:31.449372Z","steps":["trace[2089282842] 'agreement among raft nodes before linearized reading' (duration: 101.609249ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:31.449476Z","caller":"traceutil/trace.go:171","msg":"trace[1694974980] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"170.232615ms","start":"2026-04-16T02:57:31.279196Z","end":"2026-04-16T02:57:31.449429Z","steps":["trace[1694974980] 'process raft request' (duration: 116.286995ms)","trace[1694974980] 'compare' (duration: 53.367273ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:59:40.141011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.522633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:59:40.14122Z","caller":"traceutil/trace.go:171","msg":"trace[814179428] linearizableReadLoop","detail":"{readStateIndex:1760; appliedIndex:1759; }","duration":"144.970532ms","start":"2026-04-16T02:59:39.996222Z","end":"2026-04-16T02:59:40.141193Z","steps":["trace[814179428] 'read index received' (duration: 19.62µs)","trace[814179428] 'applied index is now lower than readState.Index' (duration: 144.949012ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:59:40.141316Z","caller":"traceutil/trace.go:171","msg":"trace[490187403] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"250.424511ms","start":"2026-04-16T02:59:39.89082Z","end":"2026-04-16T02:59:40.141245Z","steps":["trace[490187403] 'process raft request' (duration: 41.519381ms)","trace[490187403] 'compare' (duration: 208.270809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:59:40.14151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.249548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T02:59:40.141629Z","caller":"traceutil/trace.go:171","msg":"trace[1922484494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1685; }","duration":"145.39075ms","start":"2026-04-16T02:59:39.996215Z","end":"2026-04-16T02:59:40.141606Z","steps":["trace[1922484494] 'agreement among raft nodes before linearized reading' (duration: 145.080794ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:59:45.514239Z","caller":"traceutil/trace.go:171","msg":"trace[1825616388] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"202.468054ms","start":"2026-04-16T02:59:45.311733Z","end":"2026-04-16T02:59:45.514201Z","steps":["trace[1825616388] 'process raft request' (duration: 202.255299ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:59:56.598448Z","caller":"traceutil/trace.go:171","msg":"trace[2051613775] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"359.843885ms","start":"2026-04-16T02:59:56.238571Z","end":"2026-04-16T02:59:56.598415Z","steps":["trace[2051613775] 'process raft request' (duration: 359.569919ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:59:56.598736Z","caller":"traceutil/trace.go:171","msg":"trace[135848842] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"360.102209ms","start":"2026-04-16T02:59:56.238595Z","end":"2026-04-16T02:59:56.598697Z","steps":["trace[135848842] 'process raft request' (duration: 359.759292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:59:56.598956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:59:56.238565Z","time spent":"360.242513ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:59:56.599188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:59:56.238546Z","time spent":"359.994777ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:59:56.893401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.037667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:59:56.893491Z","caller":"traceutil/trace.go:171","msg":"trace[64091588] linearizableReadLoop","detail":"{readStateIndex:1819; appliedIndex:1816; }","duration":"629.813562ms","start":"2026-04-16T02:59:56.263655Z","end":"2026-04-16T02:59:56.893469Z","steps":["trace[64091588] 'read index received' (duration: 334.54796ms)","trace[64091588] 'applied index is now lower than readState.Index' (duration: 295.264672ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:59:56.893546Z","caller":"traceutil/trace.go:171","msg":"trace[1140204747] transaction","detail":"{read_only:false; response_revision:1741; number_of_response:1; }","duration":"653.863911ms","start":"2026-04-16T02:59:56.239666Z","end":"2026-04-16T02:59:56.89353Z","steps":["trace[1140204747] 'process raft request' (duration: 503.618219ms)","trace[1140204747] 'compare' (duration: 149.751611ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:59:56.893594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.949905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-16T02:59:56.893618Z","caller":"traceutil/trace.go:171","msg":"trace[655196738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1741; }","duration":"630.024586ms","start":"2026-04-16T02:59:56.263585Z","end":"2026-04-16T02:59:56.89361Z","steps":["trace[655196738] 'agreement among raft nodes before linearized reading' (duration: 629.931154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:59:56.893641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:59:56.26356Z","time spent":"630.076008ms","remote":"127.0.0.1:49874","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-04-16T02:59:56.893632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:59:56.239658Z","time spent":"653.931392ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:59:58.820109Z","caller":"traceutil/trace.go:171","msg":"trace[184745536] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"212.297521ms","start":"2026-04-16T02:59:58.607789Z","end":"2026-04-16T02:59:58.820087Z","steps":["trace[184745536] 'process raft request' (duration: 212.159398ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:59:58.981128Z","caller":"traceutil/trace.go:171","msg":"trace[455411931] linearizableReadLoop","detail":"{readStateIndex:1824; appliedIndex:1823; }","duration":"242.884057ms","start":"2026-04-16T02:59:58.738218Z","end":"2026-04-16T02:59:58.981102Z","steps":["trace[455411931] 'read index received' (duration: 82.091362ms)","trace[455411931] 'applied index is now lower than readState.Index' (duration: 160.791395ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:59:58.981414Z","caller":"traceutil/trace.go:171","msg":"trace[499371541] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"373.129725ms","start":"2026-04-16T02:59:58.608247Z","end":"2026-04-16T02:59:58.981377Z","steps":["trace[499371541] 'process raft request' (duration: 335.288056ms)","trace[499371541] 'compare' (duration: 37.370049ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:59:58.98148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.254115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:59:58.981544Z","caller":"traceutil/trace.go:171","msg":"trace[1334966382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1746; }","duration":"243.386168ms","start":"2026-04-16T02:59:58.738139Z","end":"2026-04-16T02:59:58.981525Z","steps":["trace[1334966382] 'agreement among raft nodes before linearized reading' (duration: 243.246155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:59:58.981599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:59:58.608228Z","time spent":"373.267469ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:59:59.579296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.333888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:59:59.579612Z","caller":"traceutil/trace.go:171","msg":"trace[516256299] transaction","detail":"{read_only:false; response_revision:1747; number_of_response:1; }","duration":"591.692828ms","start":"2026-04-16T02:59:58.987877Z","end":"2026-04-16T02:59:59.57957Z","steps":["trace[516256299] 'process raft request' (duration: 237.946992ms)","trace[516256299] 'compare' (duration: 353.159524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:59:59.579817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:59:58.987849Z","time spent":"591.853882ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:59:59.580588Z","caller":"traceutil/trace.go:171","msg":"trace[404486855] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"591.683918ms","start":"2026-04-16T02:59:58.988833Z","end":"2026-04-16T02:59:59.580517Z","steps":["trace[404486855] 'process raft request' (duration: 590.623716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:59:59.580913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:59:58.988815Z","time spent":"591.889953ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:59:59.754861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.30406ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-16T02:59:59.755962Z","caller":"traceutil/trace.go:171","msg":"trace[1460526600] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"348.801072ms","start":"2026-04-16T02:59:59.407091Z","end":"2026-04-16T02:59:59.755892Z","steps":["trace[1460526600] 'process raft request' (duration: 347.902803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:59:59.756224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:59:59.407064Z","time spent":"349.067438ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:59:59.833246Z","caller":"traceutil/trace.go:171","msg":"trace[985121507] transaction","detail":"{read_only:false; response_revision:1750; number_of_response:1; }","duration":"252.154363ms","start":"2026-04-16T02:59:59.581064Z","end":"2026-04-16T02:59:59.833218Z","steps":["trace[985121507] 'process raft request' (duration: 251.818886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:13.912527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.443135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T03:00:13.912607Z","caller":"traceutil/trace.go:171","msg":"trace[148918727] linearizableReadLoop","detail":"{readStateIndex:1875; appliedIndex:1873; }","duration":"247.415833ms","start":"2026-04-16T03:00:13.665178Z","end":"2026-04-16T03:00:13.912594Z","steps":["trace[148918727] 'read index received' (duration: 7.651432ms)","trace[148918727] 'applied index is now lower than readState.Index' (duration: 239.763581ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:00:13.91266Z","caller":"traceutil/trace.go:171","msg":"trace[1575543360] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"258.419305ms","start":"2026-04-16T03:00:13.654233Z","end":"2026-04-16T03:00:13.912652Z","steps":["trace[1575543360] 'process raft request' (duration: 98.757495ms)","trace[1575543360] 'compare' (duration: 159.281392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:00:13.91279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.592986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:00:13.912886Z","caller":"traceutil/trace.go:171","msg":"trace[286786835] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1794; }","duration":"247.729599ms","start":"2026-04-16T03:00:13.665128Z","end":"2026-04-16T03:00:13.912857Z","steps":["trace[286786835] 'agreement among raft nodes before linearized reading' (duration: 247.588566ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:00:14.246468Z","caller":"traceutil/trace.go:171","msg":"trace[364977560] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"337.740359ms","start":"2026-04-16T03:00:13.908687Z","end":"2026-04-16T03:00:14.246427Z","steps":["trace[364977560] 'process raft request' (duration: 337.430552ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:00:14.246536Z","caller":"traceutil/trace.go:171","msg":"trace[550163428] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1875; }","duration":"333.699824ms","start":"2026-04-16T03:00:13.912624Z","end":"2026-04-16T03:00:14.246324Z","steps":["trace[550163428] 'read index received' (duration: 67.779651ms)","trace[550163428] 'applied index is now lower than readState.Index' (duration: 265.919083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:00:14.24667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.710088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:00:14.246782Z","caller":"traceutil/trace.go:171","msg":"trace[2038224836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1796; }","duration":"509.881532ms","start":"2026-04-16T03:00:13.736877Z","end":"2026-04-16T03:00:14.246759Z","steps":["trace[2038224836] 'agreement among raft nodes before linearized reading' (duration: 509.709798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:14.246669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:00:13.908641Z","time spent":"337.925693ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:00:14.246829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:00:13.736827Z","time spent":"509.992554ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T03:00:14.246989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.991399ms","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-04-16T03:00:14.247072Z","caller":"traceutil/trace.go:171","msg":"trace[845624645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1796; }","duration":"171.109312ms","start":"2026-04-16T03:00:14.075951Z","end":"2026-04-16T03:00:14.24706Z","steps":["trace[845624645] 'agreement among raft nodes before linearized reading' (duration: 170.973699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:14.247239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.530451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.75\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-16T03:00:14.247291Z","caller":"traceutil/trace.go:171","msg":"trace[249929746] range","detail":"{range_begin:/registry/masterleases/199.19.213.75; range_end:; response_count:1; response_revision:1796; }","duration":"271.995311ms","start":"2026-04-16T03:00:13.975286Z","end":"2026-04-16T03:00:14.247282Z","steps":["trace[249929746] 'agreement among raft nodes before linearized reading' (duration: 271.49873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:14.543722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.352246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T03:00:14.544004Z","caller":"traceutil/trace.go:171","msg":"trace[1445971974] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"292.36316ms","start":"2026-04-16T03:00:14.251571Z","end":"2026-04-16T03:00:14.543934Z","steps":["trace[1445971974] 'process raft request' (duration: 165.680707ms)","trace[1445971974] 'compare' (duration: 125.560299ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:00:14.57559Z","caller":"traceutil/trace.go:171","msg":"trace[1453304625] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"286.491156ms","start":"2026-04-16T03:00:14.289069Z","end":"2026-04-16T03:00:14.57556Z","steps":["trace[1453304625] 'process raft request' (duration: 286.355403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:14.885495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.273528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:00:14.885586Z","caller":"traceutil/trace.go:171","msg":"trace[1420847436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1798; }","duration":"147.435382ms","start":"2026-04-16T03:00:14.738131Z","end":"2026-04-16T03:00:14.885566Z","steps":["trace[1420847436] 'range keys from in-memory index tree' (duration: 147.178447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:15.228139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.811901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-16T03:00:15.228282Z","caller":"traceutil/trace.go:171","msg":"trace[1491453526] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1799; }","duration":"140.476675ms","start":"2026-04-16T03:00:15.087769Z","end":"2026-04-16T03:00:15.228246Z","steps":["trace[1491453526] 'range keys from in-memory index tree' (duration: 139.518644ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:00:15.815047Z","caller":"traceutil/trace.go:171","msg":"trace[1703174241] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"132.384175ms","start":"2026-04-16T03:00:15.68263Z","end":"2026-04-16T03:00:15.815014Z","steps":["trace[1703174241] 'process raft request' (duration: 132.129129ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:00:16.232525Z","caller":"traceutil/trace.go:171","msg":"trace[1122684159] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"245.139194ms","start":"2026-04-16T03:00:15.987359Z","end":"2026-04-16T03:00:16.232498Z","steps":["trace[1122684159] 'process raft request' (duration: 244.794027ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:00:16.236904Z","caller":"traceutil/trace.go:171","msg":"trace[292920128] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1883; }","duration":"206.935497ms","start":"2026-04-16T03:00:16.029929Z","end":"2026-04-16T03:00:16.236864Z","steps":["trace[292920128] 'read index received' (duration: 202.236648ms)","trace[292920128] 'applied index is now lower than readState.Index' (duration: 4.697269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:00:16.237123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.124502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:00:16.237198Z","caller":"traceutil/trace.go:171","msg":"trace[1047566120] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1803; }","duration":"207.295305ms","start":"2026-04-16T03:00:16.029884Z","end":"2026-04-16T03:00:16.237179Z","steps":["trace[1047566120] 'agreement among raft nodes before linearized reading' (duration: 207.119521ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:00:16.237347Z","caller":"traceutil/trace.go:171","msg":"trace[517394398] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"241.022247ms","start":"2026-04-16T03:00:15.99629Z","end":"2026-04-16T03:00:16.237312Z","steps":["trace[517394398] 'process raft request' (duration: 240.359673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:18.743635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611846327334804,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T03:00:19.101927Z","caller":"traceutil/trace.go:171","msg":"trace[718718133] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"858.355397ms","start":"2026-04-16T03:00:18.243554Z","end":"2026-04-16T03:00:19.101909Z","steps":["trace[718718133] 'read index received' (duration: 858.220644ms)","trace[718718133] 'applied index is now lower than readState.Index' (duration: 134.013µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:00:19.102103Z","caller":"traceutil/trace.go:171","msg":"trace[1630681535] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"863.029655ms","start":"2026-04-16T03:00:18.239022Z","end":"2026-04-16T03:00:19.102052Z","steps":["trace[1630681535] 'process raft request' (duration: 862.7674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:19.102208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.00144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:00:19.102228Z","caller":"traceutil/trace.go:171","msg":"trace[575544666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1808; }","duration":"362.057792ms","start":"2026-04-16T03:00:18.740164Z","end":"2026-04-16T03:00:19.102222Z","steps":["trace[575544666] 'agreement among raft nodes before linearized reading' (duration: 362.033511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:19.102257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:00:18.740141Z","time spent":"362.109523ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T03:00:19.102349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.791476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-16T03:00:19.102387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:00:18.238997Z","time spent":"863.23229ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:00:19.102442Z","caller":"traceutil/trace.go:171","msg":"trace[1827584383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1808; }","duration":"858.941269ms","start":"2026-04-16T03:00:18.243481Z","end":"2026-04-16T03:00:19.102422Z","steps":["trace[1827584383] 'agreement among raft nodes before linearized reading' (duration: 858.674633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:19.102477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.227884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T03:00:19.102497Z","caller":"traceutil/trace.go:171","msg":"trace[413967648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1808; }","duration":"505.352726ms","start":"2026-04-16T03:00:18.597139Z","end":"2026-04-16T03:00:19.102492Z","steps":["trace[413967648] 'agreement among raft nodes before linearized reading' (duration: 505.267835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:19.102502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:00:18.243452Z","time spent":"859.036161ms","remote":"127.0.0.1:49874","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-04-16T03:00:19.102472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.161851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T03:00:19.102589Z","caller":"traceutil/trace.go:171","msg":"trace[340018583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1808; }","duration":"115.355145ms","start":"2026-04-16T03:00:18.987212Z","end":"2026-04-16T03:00:19.102567Z","steps":["trace[340018583] 'agreement among raft nodes before linearized reading' (duration: 114.992887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:19.10218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.715722ms","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":"info","ts":"2026-04-16T03:00:19.102738Z","caller":"traceutil/trace.go:171","msg":"trace[1128573778] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1808; }","duration":"667.347615ms","start":"2026-04-16T03:00:18.435378Z","end":"2026-04-16T03:00:19.102725Z","steps":["trace[1128573778] 'agreement among raft nodes before linearized reading' (duration: 666.757243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:00:19.102767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:00:18.435349Z","time spent":"667.410067ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T03:00:19.102515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:00:18.597114Z","time spent":"505.398947ms","remote":"127.0.0.1:49874","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-04-16T03:00:19.289067Z","caller":"traceutil/trace.go:171","msg":"trace[369479276] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"181.287227ms","start":"2026-04-16T03:00:19.107747Z","end":"2026-04-16T03:00:19.289034Z","steps":["trace[369479276] 'process raft request' (duration: 146.542973ms)","trace[369479276] 'compare' (duration: 34.154641ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:00:19.289611Z","caller":"traceutil/trace.go:171","msg":"trace[1806367102] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"179.804405ms","start":"2026-04-16T03:00:19.109762Z","end":"2026-04-16T03:00:19.289566Z","steps":["trace[1806367102] 'process raft request' (duration: 179.506389ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:00:19.289799Z","caller":"traceutil/trace.go:171","msg":"trace[2136654130] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"180.102612ms","start":"2026-04-16T03:00:19.109636Z","end":"2026-04-16T03:00:19.289738Z","steps":["trace[2136654130] 'process raft request' (duration: 179.412567ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:00:19.477434Z","caller":"traceutil/trace.go:171","msg":"trace[793093998] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"160.13757ms","start":"2026-04-16T03:00:19.317241Z","end":"2026-04-16T03:00:19.477378Z","steps":["trace[793093998] 'process raft request' (duration: 115.533509ms)","trace[793093998] 'compare' (duration: 44.348145ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:01:35.538652Z","caller":"traceutil/trace.go:171","msg":"trace[365812] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"124.108673ms","start":"2026-04-16T03:01:35.414517Z","end":"2026-04-16T03:01:35.538626Z","steps":["trace[365812] 'process raft request' (duration: 123.96666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:01:39.495407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.152702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:01:39.495483Z","caller":"traceutil/trace.go:171","msg":"trace[158340900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2072; }","duration":"756.262614ms","start":"2026-04-16T03:01:38.739202Z","end":"2026-04-16T03:01:39.495465Z","steps":["trace[158340900] 'range keys from in-memory index tree' (duration: 756.023988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:01:39.495559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:01:38.739184Z","time spent":"756.363166ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T03:01:39.909954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.260246ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-16T03:01:39.91047Z","caller":"traceutil/trace.go:171","msg":"trace[418120134] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2170; }","duration":"413.724124ms","start":"2026-04-16T03:01:39.496718Z","end":"2026-04-16T03:01:39.910443Z","steps":["trace[418120134] 'read index received' (duration: 39.301µs)","trace[418120134] 'applied index is now lower than readState.Index' (duration: 413.682953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:01:39.91062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.893217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:01:39.910651Z","caller":"traceutil/trace.go:171","msg":"trace[793839462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2072; }","duration":"413.969359ms","start":"2026-04-16T03:01:39.496672Z","end":"2026-04-16T03:01:39.910642Z","steps":["trace[793839462] 'agreement among raft nodes before linearized reading' (duration: 413.902978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:01:39.910679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:01:39.496654Z","time spent":"414.01882ms","remote":"127.0.0.1:49604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T03:01:39.911136Z","caller":"traceutil/trace.go:171","msg":"trace[1241602909] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"362.271737ms","start":"2026-04-16T03:01:39.548845Z","end":"2026-04-16T03:01:39.911117Z","steps":["trace[1241602909] 'process raft request' (duration: 361.315625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:01:39.911196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.084674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T03:01:39.911232Z","caller":"traceutil/trace.go:171","msg":"trace[1694608649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2073; }","duration":"201.134565ms","start":"2026-04-16T03:01:39.710088Z","end":"2026-04-16T03:01:39.911223Z","steps":["trace[1694608649] 'agreement among raft nodes before linearized reading' (duration: 201.038693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:01:39.911229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:01:39.548799Z","time spent":"362.376009ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:01:39.911518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.172723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T03:01:39.911577Z","caller":"traceutil/trace.go:171","msg":"trace[435072389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2073; }","duration":"214.259546ms","start":"2026-04-16T03:01:39.697305Z","end":"2026-04-16T03:01:39.911564Z","steps":["trace[435072389] 'agreement among raft nodes before linearized reading' (duration: 213.746274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:01:39.911599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.369957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-16T03:01:39.911687Z","caller":"traceutil/trace.go:171","msg":"trace[860647316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2073; }","duration":"204.51449ms","start":"2026-04-16T03:01:39.707148Z","end":"2026-04-16T03:01:39.911662Z","steps":["trace[860647316] 'agreement among raft nodes before linearized reading' (duration: 204.266984ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:02.217668Z","caller":"traceutil/trace.go:171","msg":"trace[1382623346] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"168.773054ms","start":"2026-04-16T03:02:02.048861Z","end":"2026-04-16T03:02:02.217634Z","steps":["trace[1382623346] 'process raft request' (duration: 89.043997ms)","trace[1382623346] 'compare' (duration: 79.562943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:02:03.196574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.726854ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:03.1967Z","caller":"traceutil/trace.go:171","msg":"trace[198157929] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2147; }","duration":"846.865147ms","start":"2026-04-16T03:02:02.349816Z","end":"2026-04-16T03:02:03.196681Z","steps":["trace[198157929] 'range keys from in-memory index tree' (duration: 846.712174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:03.19754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.480397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T03:02:03.197761Z","caller":"traceutil/trace.go:171","msg":"trace[381151113] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"975.059622ms","start":"2026-04-16T03:02:02.222671Z","end":"2026-04-16T03:02:03.197731Z","steps":["trace[381151113] 'process raft request' (duration: 486.283958ms)","trace[381151113] 'compare' (duration: 487.361242ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:03.197809Z","caller":"traceutil/trace.go:171","msg":"trace[1884520049] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2250; }","duration":"501.41746ms","start":"2026-04-16T03:02:02.696375Z","end":"2026-04-16T03:02:03.197792Z","steps":["trace[1884520049] 'read index received' (duration: 12.556154ms)","trace[1884520049] 'applied index is now lower than readState.Index' (duration: 488.860376ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:03.197813Z","caller":"traceutil/trace.go:171","msg":"trace[1253916004] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"974.957089ms","start":"2026-04-16T03:02:02.222833Z","end":"2026-04-16T03:02:03.19779Z","steps":["trace[1253916004] 'process raft request' (duration: 974.805036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:03.197988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.062017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T03:02:03.197993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.620555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-16T03:02:03.198011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:02.222825Z","time spent":"975.133664ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:02:03.198031Z","caller":"traceutil/trace.go:171","msg":"trace[1423141780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2150; }","duration":"501.695237ms","start":"2026-04-16T03:02:02.696325Z","end":"2026-04-16T03:02:03.198021Z","steps":["trace[1423141780] 'agreement among raft nodes before linearized reading' (duration: 501.562664ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:03.198032Z","caller":"traceutil/trace.go:171","msg":"trace[1230974488] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2150; }","duration":"194.134368ms","start":"2026-04-16T03:02:03.003883Z","end":"2026-04-16T03:02:03.198017Z","steps":["trace[1230974488] 'agreement among raft nodes before linearized reading' (duration: 194.070687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:03.198066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:02.696297Z","time spent":"501.761298ms","remote":"127.0.0.1:49874","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-04-16T03:02:03.198097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.963557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T03:02:03.197893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:02.222649Z","time spent":"975.171405ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:02:03.198168Z","caller":"traceutil/trace.go:171","msg":"trace[1050968698] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2150; }","duration":"447.054108ms","start":"2026-04-16T03:02:02.751093Z","end":"2026-04-16T03:02:03.198147Z","steps":["trace[1050968698] 'agreement among raft nodes before linearized reading' (duration: 446.954416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:03.198257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:02.751081Z","time spent":"447.157971ms","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-04-16T03:02:03.197987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.507401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:03.198455Z","caller":"traceutil/trace.go:171","msg":"trace[135355940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2150; }","duration":"460.043083ms","start":"2026-04-16T03:02:02.738394Z","end":"2026-04-16T03:02:03.198438Z","steps":["trace[135355940] 'agreement among raft nodes before linearized reading' (duration: 459.511751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:03.198517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:02.738379Z","time spent":"460.122335ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T03:02:03.199005Z","caller":"traceutil/trace.go:171","msg":"trace[2008259127] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"975.976753ms","start":"2026-04-16T03:02:02.222927Z","end":"2026-04-16T03:02:03.198903Z","steps":["trace[2008259127] 'process raft request' (duration: 974.820326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:03.199149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:02.222911Z","time spent":"976.192998ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:02:03.66074Z","caller":"traceutil/trace.go:171","msg":"trace[360717726] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"456.984284ms","start":"2026-04-16T03:02:03.20372Z","end":"2026-04-16T03:02:03.660704Z","steps":["trace[360717726] 'process raft request' (duration: 414.842699ms)","trace[360717726] 'compare' (duration: 41.884159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:02:03.661049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:03.203701Z","time spent":"457.130578ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:02:05.831626Z","caller":"traceutil/trace.go:171","msg":"trace[971331091] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2259; }","duration":"165.674184ms","start":"2026-04-16T03:02:05.665922Z","end":"2026-04-16T03:02:05.831597Z","steps":["trace[971331091] 'read index received' (duration: 100.421286ms)","trace[971331091] 'applied index is now lower than readState.Index' (duration: 65.251428ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:05.831803Z","caller":"traceutil/trace.go:171","msg":"trace[27690948] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"623.617759ms","start":"2026-04-16T03:02:05.208151Z","end":"2026-04-16T03:02:05.831768Z","steps":["trace[27690948] 'process raft request' (duration: 558.308319ms)","trace[27690948] 'compare' (duration: 64.909071ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:05.832158Z","caller":"traceutil/trace.go:171","msg":"trace[441132652] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"622.853741ms","start":"2026-04-16T03:02:05.209275Z","end":"2026-04-16T03:02:05.832129Z","steps":["trace[441132652] 'process raft request' (duration: 622.246218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:05.832228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:05.208132Z","time spent":"623.988967ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:02:05.83224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.282028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-16T03:02:05.832299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:05.209266Z","time spent":"622.963484ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:02:05.832318Z","caller":"traceutil/trace.go:171","msg":"trace[1437018512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2157; }","duration":"166.434521ms","start":"2026-04-16T03:02:05.665868Z","end":"2026-04-16T03:02:05.832303Z","steps":["trace[1437018512] 'agreement among raft nodes before linearized reading' (duration: 166.170105ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:06.060107Z","caller":"traceutil/trace.go:171","msg":"trace[2145513338] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"148.122286ms","start":"2026-04-16T03:02:05.911949Z","end":"2026-04-16T03:02:06.060071Z","steps":["trace[2145513338] 'process raft request' (duration: 147.868081ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:07.427136Z","caller":"traceutil/trace.go:171","msg":"trace[1541319759] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"128.602084ms","start":"2026-04-16T03:02:07.298497Z","end":"2026-04-16T03:02:07.427099Z","steps":["trace[1541319759] 'process raft request' (duration: 128.456931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:08.042623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.825407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T03:02:08.042731Z","caller":"traceutil/trace.go:171","msg":"trace[418136480] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2162; }","duration":"368.989791ms","start":"2026-04-16T03:02:07.673719Z","end":"2026-04-16T03:02:08.042708Z","steps":["trace[418136480] 'count revisions from in-memory index tree' (duration: 368.690004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:08.042718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.020351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T03:02:08.042768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.874985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-16T03:02:08.042788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:07.673686Z","time spent":"369.085393ms","remote":"127.0.0.1:54276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-04-16T03:02:08.042802Z","caller":"traceutil/trace.go:171","msg":"trace[814220115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2162; }","duration":"305.127694ms","start":"2026-04-16T03:02:07.737656Z","end":"2026-04-16T03:02:08.042784Z","steps":["trace[814220115] 'range keys from in-memory index tree' (duration: 304.926179ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:08.04282Z","caller":"traceutil/trace.go:171","msg":"trace[426333261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2162; }","duration":"205.962726ms","start":"2026-04-16T03:02:07.836843Z","end":"2026-04-16T03:02:08.042806Z","steps":["trace[426333261] 'range keys from in-memory index tree' (duration: 205.707771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:08.042852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:07.737641Z","time spent":"305.196265ms","remote":"127.0.0.1:49604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T03:02:08.043097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.10728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T03:02:08.043146Z","caller":"traceutil/trace.go:171","msg":"trace[828101561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2162; }","duration":"206.195492ms","start":"2026-04-16T03:02:07.836936Z","end":"2026-04-16T03:02:08.043131Z","steps":["trace[828101561] 'range keys from in-memory index tree' (duration: 205.969877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:08.043277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.06502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T03:02:08.043371Z","caller":"traceutil/trace.go:171","msg":"trace[1915068731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2162; }","duration":"169.212474ms","start":"2026-04-16T03:02:07.874135Z","end":"2026-04-16T03:02:08.043348Z","steps":["trace[1915068731] 'range keys from in-memory index tree' (duration: 168.830885ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:08.285869Z","caller":"traceutil/trace.go:171","msg":"trace[1435650107] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"237.721387ms","start":"2026-04-16T03:02:08.048124Z","end":"2026-04-16T03:02:08.285846Z","steps":["trace[1435650107] 'process raft request' (duration: 237.539123ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:08.286066Z","caller":"traceutil/trace.go:171","msg":"trace[102763110] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"237.558083ms","start":"2026-04-16T03:02:08.048498Z","end":"2026-04-16T03:02:08.286056Z","steps":["trace[102763110] 'process raft request' (duration: 237.310217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:19.745057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.662665ms","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-04-16T03:02:19.7452Z","caller":"traceutil/trace.go:171","msg":"trace[1829358034] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2252; }","duration":"117.838199ms","start":"2026-04-16T03:02:19.627325Z","end":"2026-04-16T03:02:19.745163Z","steps":["trace[1829358034] 'count revisions from in-memory index tree' (duration: 117.583123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:20.57375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.434132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T03:02:20.573823Z","caller":"traceutil/trace.go:171","msg":"trace[1682675443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2253; }","duration":"234.542514ms","start":"2026-04-16T03:02:20.339263Z","end":"2026-04-16T03:02:20.573805Z","steps":["trace[1682675443] 'range keys from in-memory index tree' (duration: 234.275438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:20.574015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.089426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T03:02:20.574072Z","caller":"traceutil/trace.go:171","msg":"trace[1812884089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2253; }","duration":"220.181359ms","start":"2026-04-16T03:02:20.353875Z","end":"2026-04-16T03:02:20.574057Z","steps":["trace[1812884089] 'range keys from in-memory index tree' (duration: 219.924423ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:20.576078Z","caller":"traceutil/trace.go:171","msg":"trace[1548017844] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"131.117771ms","start":"2026-04-16T03:02:20.444948Z","end":"2026-04-16T03:02:20.576065Z","steps":["trace[1548017844] 'process raft request' (duration: 130.825674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:22.060665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611846327335954,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T03:02:22.199743Z","caller":"traceutil/trace.go:171","msg":"trace[668285972] linearizableReadLoop","detail":"{readStateIndex:2367; appliedIndex:2366; }","duration":"639.53217ms","start":"2026-04-16T03:02:21.560194Z","end":"2026-04-16T03:02:22.199726Z","steps":["trace[668285972] 'read index received' (duration: 639.334516ms)","trace[668285972] 'applied index is now lower than readState.Index' (duration: 196.954µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:22.199874Z","caller":"traceutil/trace.go:171","msg":"trace[1739903269] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"693.924003ms","start":"2026-04-16T03:02:21.505927Z","end":"2026-04-16T03:02:22.199851Z","steps":["trace[1739903269] 'process raft request' (duration: 693.671727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:22.199914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.696464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:22.199968Z","caller":"traceutil/trace.go:171","msg":"trace[949520879] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2258; }","duration":"639.790466ms","start":"2026-04-16T03:02:21.560148Z","end":"2026-04-16T03:02:22.199939Z","steps":["trace[949520879] 'agreement among raft nodes before linearized reading' (duration: 639.681733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:22.19999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:21.505896Z","time spent":"694.033805ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:02:22.200009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:21.560126Z","time spent":"639.872758ms","remote":"127.0.0.1:54306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T03:02:22.200003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.368096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:22.200087Z","caller":"traceutil/trace.go:171","msg":"trace[583437192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2258; }","duration":"462.501279ms","start":"2026-04-16T03:02:21.737564Z","end":"2026-04-16T03:02:22.200065Z","steps":["trace[583437192] 'agreement among raft nodes before linearized reading' (duration: 462.348005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:22.200139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:21.73754Z","time spent":"462.591551ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T03:02:22.863419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9228611846327335959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T03:02:23.117585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.597112ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:23.117762Z","caller":"traceutil/trace.go:171","msg":"trace[2073614610] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2258; }","duration":"767.795716ms","start":"2026-04-16T03:02:22.349943Z","end":"2026-04-16T03:02:23.117739Z","steps":["trace[2073614610] 'range keys from in-memory index tree' (duration: 767.576551ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:23.118001Z","caller":"traceutil/trace.go:171","msg":"trace[189619387] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"903.829959ms","start":"2026-04-16T03:02:22.214133Z","end":"2026-04-16T03:02:23.117963Z","steps":["trace[189619387] 'process raft request' (duration: 900.277599ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:23.118011Z","caller":"traceutil/trace.go:171","msg":"trace[245481391] linearizableReadLoop","detail":"{readStateIndex:2370; appliedIndex:2367; }","duration":"755.668491ms","start":"2026-04-16T03:02:22.362308Z","end":"2026-04-16T03:02:23.117977Z","steps":["trace[245481391] 'read index received' (duration: 752.286135ms)","trace[245481391] 'applied index is now lower than readState.Index' (duration: 3.381226ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:23.118147Z","caller":"traceutil/trace.go:171","msg":"trace[1615940858] transaction","detail":"{read_only:false; response_revision:2260; number_of_response:1; }","duration":"581.110287ms","start":"2026-04-16T03:02:22.537022Z","end":"2026-04-16T03:02:23.118132Z","steps":["trace[1615940858] 'process raft request' (duration: 580.79617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:23.118218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:22.214116Z","time spent":"903.965562ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:02:23.118294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:22.536975Z","time spent":"581.201579ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:02:23.118394Z","caller":"traceutil/trace.go:171","msg":"trace[337294434] transaction","detail":"{read_only:false; response_revision:2261; number_of_response:1; }","duration":"536.446495ms","start":"2026-04-16T03:02:22.581932Z","end":"2026-04-16T03:02:23.118379Z","steps":["trace[337294434] 'process raft request' (duration: 536.003035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:23.118496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:22.581907Z","time spent":"536.523956ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:02:23.118594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.460412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T03:02:23.118625Z","caller":"traceutil/trace.go:171","msg":"trace[1292690790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2261; }","duration":"535.519973ms","start":"2026-04-16T03:02:22.583097Z","end":"2026-04-16T03:02:23.118617Z","steps":["trace[1292690790] 'agreement among raft nodes before linearized reading' (duration: 535.38777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:23.118645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:22.583085Z","time spent":"535.555625ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T03:02:23.118309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.993888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:23.118734Z","caller":"traceutil/trace.go:171","msg":"trace[1671903435] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2261; }","duration":"756.49758ms","start":"2026-04-16T03:02:22.362229Z","end":"2026-04-16T03:02:23.118727Z","steps":["trace[1671903435] 'agreement among raft nodes before linearized reading' (duration: 756.012279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:23.118754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:22.3622Z","time spent":"756.547072ms","remote":"127.0.0.1:49768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-04-16T03:02:23.118865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.064439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T03:02:23.118908Z","caller":"traceutil/trace.go:171","msg":"trace[1980110302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2261; }","duration":"530.160322ms","start":"2026-04-16T03:02:22.588735Z","end":"2026-04-16T03:02:23.118895Z","steps":["trace[1980110302] 'agreement among raft nodes before linearized reading' (duration: 530.021169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:23.118943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.626012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-16T03:02:23.118947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:22.588707Z","time spent":"530.230713ms","remote":"127.0.0.1:49874","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-04-16T03:02:23.11897Z","caller":"traceutil/trace.go:171","msg":"trace[2101433451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2261; }","duration":"530.689204ms","start":"2026-04-16T03:02:22.588275Z","end":"2026-04-16T03:02:23.118964Z","steps":["trace[2101433451] 'agreement among raft nodes before linearized reading' (duration: 530.609202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:23.118967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.680693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T03:02:23.118993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:22.588254Z","time spent":"530.733475ms","remote":"127.0.0.1:49874","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-04-16T03:02:23.119009Z","caller":"traceutil/trace.go:171","msg":"trace[1736109184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2261; }","duration":"379.756535ms","start":"2026-04-16T03:02:22.739239Z","end":"2026-04-16T03:02:23.118996Z","steps":["trace[1736109184] 'agreement among raft nodes before linearized reading' (duration: 379.688813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:23.119038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:22.73922Z","time spent":"379.812275ms","remote":"127.0.0.1:49602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T03:02:36.869746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.992355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:36.869857Z","caller":"traceutil/trace.go:171","msg":"trace[184682344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2305; }","duration":"130.149599ms","start":"2026-04-16T03:02:36.739681Z","end":"2026-04-16T03:02:36.86983Z","steps":["trace[184682344] 'range keys from in-memory index tree' (duration: 129.908463ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:36.870022Z","caller":"traceutil/trace.go:171","msg":"trace[663346058] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"110.966244ms","start":"2026-04-16T03:02:36.75902Z","end":"2026-04-16T03:02:36.869986Z","steps":["trace[663346058] 'process raft request' (duration: 62.631799ms)","trace[663346058] 'compare' (duration: 47.949887ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:37.528934Z","caller":"traceutil/trace.go:171","msg":"trace[948163625] transaction","detail":"{read_only:false; response_revision:2308; number_of_response:1; }","duration":"280.237859ms","start":"2026-04-16T03:02:37.248629Z","end":"2026-04-16T03:02:37.528867Z","steps":["trace[948163625] 'process raft request' (duration: 264.438791ms)","trace[948163625] 'compare' (duration: 15.527462ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:37.5948Z","caller":"traceutil/trace.go:171","msg":"trace[603104205] transaction","detail":"{read_only:false; response_revision:2309; number_of_response:1; }","duration":"345.013128ms","start":"2026-04-16T03:02:37.249764Z","end":"2026-04-16T03:02:37.594778Z","steps":["trace[603104205] 'process raft request' (duration: 344.794403ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:37.5948Z","caller":"traceutil/trace.go:171","msg":"trace[1692974478] transaction","detail":"{read_only:false; response_revision:2311; number_of_response:1; }","duration":"344.349383ms","start":"2026-04-16T03:02:37.250422Z","end":"2026-04-16T03:02:37.594771Z","steps":["trace[1692974478] 'process raft request' (duration: 344.307622ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:37.594862Z","caller":"traceutil/trace.go:171","msg":"trace[270557541] transaction","detail":"{read_only:false; response_revision:2310; number_of_response:1; }","duration":"344.981176ms","start":"2026-04-16T03:02:37.249857Z","end":"2026-04-16T03:02:37.594838Z","steps":["trace[270557541] 'process raft request' (duration: 344.836373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:37.594974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:37.249848Z","time spent":"345.062069ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:02:37.594974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:37.250407Z","time spent":"344.471315ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:02:37.594964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:37.249741Z","time spent":"345.112819ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:02:43.702829Z","caller":"traceutil/trace.go:171","msg":"trace[1604355959] transaction","detail":"{read_only:false; response_revision:2374; number_of_response:1; }","duration":"145.704331ms","start":"2026-04-16T03:02:43.557109Z","end":"2026-04-16T03:02:43.702813Z","steps":["trace[1604355959] 'process raft request' (duration: 145.615459ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:44.094514Z","caller":"traceutil/trace.go:171","msg":"trace[852034102] linearizableReadLoop","detail":"{readStateIndex:2488; appliedIndex:2487; }","duration":"476.524257ms","start":"2026-04-16T03:02:43.617959Z","end":"2026-04-16T03:02:44.094483Z","steps":["trace[852034102] 'read index received' (duration: 84.854182ms)","trace[852034102] 'applied index is now lower than readState.Index' (duration: 391.669015ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:44.094652Z","caller":"traceutil/trace.go:171","msg":"trace[1347478795] transaction","detail":"{read_only:false; response_revision:2375; number_of_response:1; }","duration":"515.645803ms","start":"2026-04-16T03:02:43.578967Z","end":"2026-04-16T03:02:44.094613Z","steps":["trace[1347478795] 'process raft request' (duration: 515.144302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:44.094804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.812613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T03:02:44.09483Z","caller":"traceutil/trace.go:171","msg":"trace[490757846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2375; }","duration":"476.906325ms","start":"2026-04-16T03:02:43.617917Z","end":"2026-04-16T03:02:44.094823Z","steps":["trace[490757846] 'agreement among raft nodes before linearized reading' (duration: 476.735531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:44.094856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:43.617893Z","time spent":"476.957057ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T03:02:44.094855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:43.578947Z","time spent":"515.808507ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:02:44.094892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.116106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T03:02:44.094926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.66826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T03:02:44.094983Z","caller":"traceutil/trace.go:171","msg":"trace[1942628161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2375; }","duration":"118.239669ms","start":"2026-04-16T03:02:43.97672Z","end":"2026-04-16T03:02:44.094959Z","steps":["trace[1942628161] 'agreement among raft nodes before linearized reading' (duration: 118.098646ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:44.095015Z","caller":"traceutil/trace.go:171","msg":"trace[1283276301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2375; }","duration":"476.750742ms","start":"2026-04-16T03:02:43.618224Z","end":"2026-04-16T03:02:44.094974Z","steps":["trace[1283276301] 'agreement among raft nodes before linearized reading' (duration: 476.556067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:44.095019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.416004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-16T03:02:44.095063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:43.618216Z","time spent":"476.837773ms","remote":"127.0.0.1:49874","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-04-16T03:02:44.09508Z","caller":"traceutil/trace.go:171","msg":"trace[932037616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2375; }","duration":"476.511807ms","start":"2026-04-16T03:02:43.618553Z","end":"2026-04-16T03:02:44.095065Z","steps":["trace[932037616] 'agreement among raft nodes before linearized reading' (duration: 476.292832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:44.095113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:43.618547Z","time spent":"476.560538ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T03:02:44.095013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.08961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.75\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-04-16T03:02:44.095446Z","caller":"traceutil/trace.go:171","msg":"trace[307167994] range","detail":"{range_begin:/registry/masterleases/199.19.213.75; range_end:; response_count:1; response_revision:2375; }","duration":"112.55905ms","start":"2026-04-16T03:02:43.982873Z","end":"2026-04-16T03:02:44.095432Z","steps":["trace[307167994] 'agreement among raft nodes before linearized reading' (duration: 112.008068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:44.315731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.349058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T03:02:44.315912Z","caller":"traceutil/trace.go:171","msg":"trace[153620603] transaction","detail":"{read_only:false; response_revision:2376; number_of_response:1; }","duration":"216.476935ms","start":"2026-04-16T03:02:44.099413Z","end":"2026-04-16T03:02:44.31589Z","steps":["trace[153620603] 'process raft request' (duration: 87.861271ms)","trace[153620603] 'compare' (duration: 128.185764ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:44.316596Z","caller":"traceutil/trace.go:171","msg":"trace[111728008] transaction","detail":"{read_only:false; response_revision:2377; number_of_response:1; }","duration":"216.792402ms","start":"2026-04-16T03:02:44.099771Z","end":"2026-04-16T03:02:44.316563Z","steps":["trace[111728008] 'process raft request' (duration: 216.069156ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:44.316869Z","caller":"traceutil/trace.go:171","msg":"trace[1776296647] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"175.037286ms","start":"2026-04-16T03:02:44.141816Z","end":"2026-04-16T03:02:44.316854Z","steps":["trace[1776296647] 'process raft request' (duration: 174.898003ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:44.316867Z","caller":"traceutil/trace.go:171","msg":"trace[1502175318] transaction","detail":"{read_only:false; response_revision:2378; number_of_response:1; }","duration":"216.330271ms","start":"2026-04-16T03:02:44.100502Z","end":"2026-04-16T03:02:44.316832Z","steps":["trace[1502175318] 'process raft request' (duration: 215.977083ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:44.316809Z","caller":"traceutil/trace.go:171","msg":"trace[1475057233] linearizableReadLoop","detail":"{readStateIndex:2493; appliedIndex:2489; }","duration":"153.514378ms","start":"2026-04-16T03:02:44.163265Z","end":"2026-04-16T03:02:44.316779Z","steps":["trace[1475057233] 'read index received' (duration: 24.005044ms)","trace[1475057233] 'applied index is now lower than readState.Index' (duration: 129.507864ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:02:44.317145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.802675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:44.318589Z","caller":"traceutil/trace.go:171","msg":"trace[1697477062] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2379; }","duration":"155.36511ms","start":"2026-04-16T03:02:44.163205Z","end":"2026-04-16T03:02:44.31857Z","steps":["trace[1697477062] 'agreement among raft nodes before linearized reading' (duration: 153.735933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.038328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.596581ms","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":"warn","ts":"2026-04-16T03:02:46.038389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.631624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T03:02:46.038447Z","caller":"traceutil/trace.go:171","msg":"trace[733062480] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2382; }","duration":"571.781166ms","start":"2026-04-16T03:02:45.466629Z","end":"2026-04-16T03:02:46.03841Z","steps":["trace[733062480] 'count revisions from in-memory index tree' (duration: 571.477758ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.038469Z","caller":"traceutil/trace.go:171","msg":"trace[76550792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2382; }","duration":"331.767047ms","start":"2026-04-16T03:02:45.706683Z","end":"2026-04-16T03:02:46.03845Z","steps":["trace[76550792] 'range keys from in-memory index tree' (duration: 331.45115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.038503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:45.466609Z","time spent":"571.882878ms","remote":"127.0.0.1:49700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":151,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-16T03:02:46.038517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:45.706664Z","time spent":"331.844049ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T03:02:46.038697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.125378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:46.038812Z","caller":"traceutil/trace.go:171","msg":"trace[1687230813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2382; }","duration":"299.291001ms","start":"2026-04-16T03:02:45.739504Z","end":"2026-04-16T03:02:46.038795Z","steps":["trace[1687230813] 'range keys from in-memory index tree' (duration: 299.033375ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.051286Z","caller":"traceutil/trace.go:171","msg":"trace[1793832043] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"226.260637ms","start":"2026-04-16T03:02:45.82501Z","end":"2026-04-16T03:02:46.051271Z","steps":["trace[1793832043] 'process raft request' (duration: 226.118984ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.17185Z","caller":"traceutil/trace.go:171","msg":"trace[383063050] linearizableReadLoop","detail":"{readStateIndex:2498; appliedIndex:2496; }","duration":"132.050622ms","start":"2026-04-16T03:02:46.039782Z","end":"2026-04-16T03:02:46.171833Z","steps":["trace[383063050] 'read index received' (duration: 11.404678ms)","trace[383063050] 'applied index is now lower than readState.Index' (duration: 120.645264ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:46.172084Z","caller":"traceutil/trace.go:171","msg":"trace[974277732] transaction","detail":"{read_only:false; response_revision:2384; number_of_response:1; }","duration":"203.03314ms","start":"2026-04-16T03:02:45.969044Z","end":"2026-04-16T03:02:46.172077Z","steps":["trace[974277732] 'process raft request' (duration: 202.654712ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.172126Z","caller":"traceutil/trace.go:171","msg":"trace[1562781978] transaction","detail":"{read_only:false; response_revision:2385; number_of_response:1; }","duration":"128.944022ms","start":"2026-04-16T03:02:46.043156Z","end":"2026-04-16T03:02:46.1721Z","steps":["trace[1562781978] 'process raft request' (duration: 128.646615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.172252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.44271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:46.172338Z","caller":"traceutil/trace.go:171","msg":"trace[899557342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2385; }","duration":"132.585934ms","start":"2026-04-16T03:02:46.039733Z","end":"2026-04-16T03:02:46.172319Z","steps":["trace[899557342] 'agreement among raft nodes before linearized reading' (duration: 132.42341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.241866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.222141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"warn","ts":"2026-04-16T03:02:46.241882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.374218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1783"} {"level":"info","ts":"2026-04-16T03:02:46.241944Z","caller":"traceutil/trace.go:171","msg":"trace[870725572] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:2387; }","duration":"119.329434ms","start":"2026-04-16T03:02:46.122596Z","end":"2026-04-16T03:02:46.241925Z","steps":["trace[870725572] 'agreement among raft nodes before linearized reading' (duration: 119.173731ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.241963Z","caller":"traceutil/trace.go:171","msg":"trace[1592029129] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2387; }","duration":"184.517411ms","start":"2026-04-16T03:02:46.057427Z","end":"2026-04-16T03:02:46.241944Z","steps":["trace[1592029129] 'agreement among raft nodes before linearized reading' (duration: 184.285136ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.241856Z","caller":"traceutil/trace.go:171","msg":"trace[2084614858] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"168.040608ms","start":"2026-04-16T03:02:46.073778Z","end":"2026-04-16T03:02:46.241819Z","steps":["trace[2084614858] 'process raft request' (duration: 167.792502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.24215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.991307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-04-16T03:02:46.242229Z","caller":"traceutil/trace.go:171","msg":"trace[1776075659] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:2387; }","duration":"134.113759ms","start":"2026-04-16T03:02:46.108102Z","end":"2026-04-16T03:02:46.242216Z","steps":["trace[1776075659] 'agreement among raft nodes before linearized reading' (duration: 133.558176ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.24168Z","caller":"traceutil/trace.go:171","msg":"trace[766329985] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"184.932991ms","start":"2026-04-16T03:02:46.056722Z","end":"2026-04-16T03:02:46.241655Z","steps":["trace[766329985] 'process raft request' (duration: 184.720536ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.377883Z","caller":"traceutil/trace.go:171","msg":"trace[400005349] linearizableReadLoop","detail":"{readStateIndex:2502; appliedIndex:2501; }","duration":"131.331466ms","start":"2026-04-16T03:02:46.246524Z","end":"2026-04-16T03:02:46.377856Z","steps":["trace[400005349] 'read index received' (duration: 31.512554ms)","trace[400005349] 'applied index is now lower than readState.Index' (duration: 99.817452ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:46.378018Z","caller":"traceutil/trace.go:171","msg":"trace[594322363] transaction","detail":"{read_only:false; response_revision:2388; number_of_response:1; }","duration":"131.739005ms","start":"2026-04-16T03:02:46.246241Z","end":"2026-04-16T03:02:46.37798Z","steps":["trace[594322363] 'process raft request' (duration: 31.79547ms)","trace[594322363] 'compare' (duration: 99.686549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:02:46.37811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.572341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-04-16T03:02:46.378191Z","caller":"traceutil/trace.go:171","msg":"trace[1309126114] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:2388; }","duration":"131.690914ms","start":"2026-04-16T03:02:46.246483Z","end":"2026-04-16T03:02:46.378174Z","steps":["trace[1309126114] 'agreement among raft nodes before linearized reading' (duration: 131.492639ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.435055Z","caller":"traceutil/trace.go:171","msg":"trace[1824864738] transaction","detail":"{read_only:false; response_revision:2389; number_of_response:1; }","duration":"186.378893ms","start":"2026-04-16T03:02:46.248642Z","end":"2026-04-16T03:02:46.435021Z","steps":["trace[1824864738] 'process raft request' (duration: 186.23708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.663976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.080687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T03:02:46.664173Z","caller":"traceutil/trace.go:171","msg":"trace[1459877288] linearizableReadLoop","detail":"{readStateIndex:2504; appliedIndex:2502; }","duration":"286.207235ms","start":"2026-04-16T03:02:46.377949Z","end":"2026-04-16T03:02:46.664156Z","steps":["trace[1459877288] 'read index received' (duration: 56.910029ms)","trace[1459877288] 'applied index is now lower than readState.Index' (duration: 229.296256ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:46.66421Z","caller":"traceutil/trace.go:171","msg":"trace[2054046489] transaction","detail":"{read_only:false; response_revision:2390; number_of_response:1; }","duration":"350.330628ms","start":"2026-04-16T03:02:46.313833Z","end":"2026-04-16T03:02:46.664163Z","steps":["trace[2054046489] 'process raft request' (duration: 208.997305ms)","trace[2054046489] 'compare' (duration: 140.945604ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:46.664267Z","caller":"traceutil/trace.go:171","msg":"trace[307000601] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"279.486593ms","start":"2026-04-16T03:02:46.38477Z","end":"2026-04-16T03:02:46.664257Z","steps":["trace[307000601] 'process raft request' (duration: 279.295179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.664373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:46.313804Z","time spent":"350.491191ms","remote":"127.0.0.1:49700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T03:02:46.66447Z","caller":"traceutil/trace.go:171","msg":"trace[606962002] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"277.61934ms","start":"2026-04-16T03:02:46.386818Z","end":"2026-04-16T03:02:46.664437Z","steps":["trace[606962002] 'process raft request' (duration: 277.293873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.664572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.468825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T03:02:46.664612Z","caller":"traceutil/trace.go:171","msg":"trace[84071918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2392; }","duration":"344.541267ms","start":"2026-04-16T03:02:46.320061Z","end":"2026-04-16T03:02:46.664602Z","steps":["trace[84071918] 'agreement among raft nodes before linearized reading' (duration: 344.407924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.664655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:46.320045Z","time spent":"344.596408ms","remote":"127.0.0.1:49874","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-04-16T03:02:46.66477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.675797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T03:02:46.664816Z","caller":"traceutil/trace.go:171","msg":"trace[1408441166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2392; }","duration":"343.748688ms","start":"2026-04-16T03:02:46.321057Z","end":"2026-04-16T03:02:46.664805Z","steps":["trace[1408441166] 'agreement among raft nodes before linearized reading' (duration: 343.565734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.664857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:46.321052Z","time spent":"343.791629ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T03:02:46.664872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.132179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-16T03:02:46.664953Z","caller":"traceutil/trace.go:171","msg":"trace[326989383] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2392; }","duration":"281.274893ms","start":"2026-04-16T03:02:46.383654Z","end":"2026-04-16T03:02:46.664929Z","steps":["trace[326989383] 'agreement among raft nodes before linearized reading' (duration: 281.106199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.665064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.114202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-04-16T03:02:46.665067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.16083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-16T03:02:46.665143Z","caller":"traceutil/trace.go:171","msg":"trace[505754472] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2392; }","duration":"391.218215ms","start":"2026-04-16T03:02:46.27391Z","end":"2026-04-16T03:02:46.665128Z","steps":["trace[505754472] 'agreement among raft nodes before linearized reading' (duration: 391.0248ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.665149Z","caller":"traceutil/trace.go:171","msg":"trace[2107018583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2392; }","duration":"104.288623ms","start":"2026-04-16T03:02:46.560846Z","end":"2026-04-16T03:02:46.665135Z","steps":["trace[2107018583] 'agreement among raft nodes before linearized reading' (duration: 103.968906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.665134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.820721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-16T03:02:46.665223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:46.273899Z","time spent":"391.283486ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-16T03:02:46.665329Z","caller":"traceutil/trace.go:171","msg":"trace[1722216481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2392; }","duration":"344.220119ms","start":"2026-04-16T03:02:46.321025Z","end":"2026-04-16T03:02:46.665245Z","steps":["trace[1722216481] 'agreement among raft nodes before linearized reading' (duration: 343.275788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:46.665521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:46.321019Z","time spent":"344.382333ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T03:02:46.665095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.372657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-16T03:02:46.666624Z","caller":"traceutil/trace.go:171","msg":"trace[1946560788] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:2392; }","duration":"230.950023ms","start":"2026-04-16T03:02:46.435636Z","end":"2026-04-16T03:02:46.666586Z","steps":["trace[1946560788] 'agreement among raft nodes before linearized reading' (duration: 229.322356ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.942193Z","caller":"traceutil/trace.go:171","msg":"trace[481898817] transaction","detail":"{read_only:false; response_revision:2394; number_of_response:1; }","duration":"272.580536ms","start":"2026-04-16T03:02:46.669579Z","end":"2026-04-16T03:02:46.942159Z","steps":["trace[481898817] 'process raft request' (duration: 259.690484ms)","trace[481898817] 'compare' (duration: 12.681918ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:02:46.942616Z","caller":"traceutil/trace.go:171","msg":"trace[989887252] transaction","detail":"{read_only:false; response_revision:2395; number_of_response:1; }","duration":"272.639377ms","start":"2026-04-16T03:02:46.669958Z","end":"2026-04-16T03:02:46.942598Z","steps":["trace[989887252] 'process raft request' (duration: 272.131696ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.94286Z","caller":"traceutil/trace.go:171","msg":"trace[1856782216] transaction","detail":"{read_only:false; response_revision:2396; number_of_response:1; }","duration":"272.684488ms","start":"2026-04-16T03:02:46.670135Z","end":"2026-04-16T03:02:46.94282Z","steps":["trace[1856782216] 'process raft request' (duration: 272.211938ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.943083Z","caller":"traceutil/trace.go:171","msg":"trace[325264529] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"272.881163ms","start":"2026-04-16T03:02:46.67019Z","end":"2026-04-16T03:02:46.943072Z","steps":["trace[325264529] 'process raft request' (duration: 272.509235ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.943382Z","caller":"traceutil/trace.go:171","msg":"trace[1033206048] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"272.525405ms","start":"2026-04-16T03:02:46.670848Z","end":"2026-04-16T03:02:46.943374Z","steps":["trace[1033206048] 'process raft request' (duration: 272.446753ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:46.943398Z","caller":"traceutil/trace.go:171","msg":"trace[372204135] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"272.437033ms","start":"2026-04-16T03:02:46.670944Z","end":"2026-04-16T03:02:46.943382Z","steps":["trace[372204135] 'process raft request' (duration: 272.404582ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:47.527198Z","caller":"traceutil/trace.go:171","msg":"trace[470130187] transaction","detail":"{read_only:false; response_revision:2400; number_of_response:1; }","duration":"459.477841ms","start":"2026-04-16T03:02:47.067679Z","end":"2026-04-16T03:02:47.527157Z","steps":["trace[470130187] 'process raft request' (duration: 459.298337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:47.527494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:02:47.067656Z","time spent":"459.623143ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:02:47.543449Z","caller":"traceutil/trace.go:171","msg":"trace[2086422063] linearizableReadLoop","detail":"{readStateIndex:2515; appliedIndex:2513; }","duration":"104.669332ms","start":"2026-04-16T03:02:47.438759Z","end":"2026-04-16T03:02:47.543428Z","steps":["trace[2086422063] 'read index received' (duration: 88.253469ms)","trace[2086422063] 'applied index is now lower than readState.Index' (duration: 16.414833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:02:47.543616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.843215ms","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":"info","ts":"2026-04-16T03:02:47.543646Z","caller":"traceutil/trace.go:171","msg":"trace[338910598] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2401; }","duration":"104.925517ms","start":"2026-04-16T03:02:47.438711Z","end":"2026-04-16T03:02:47.543637Z","steps":["trace[338910598] 'agreement among raft nodes before linearized reading' (duration: 104.805175ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:02:47.543896Z","caller":"traceutil/trace.go:171","msg":"trace[1071985030] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"173.86993ms","start":"2026-04-16T03:02:47.369951Z","end":"2026-04-16T03:02:47.543821Z","steps":["trace[1071985030] 'process raft request' (duration: 173.354978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:02:47.847482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.468251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:02:47.84759Z","caller":"traceutil/trace.go:171","msg":"trace[9239351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2402; }","duration":"109.645035ms","start":"2026-04-16T03:02:47.73792Z","end":"2026-04-16T03:02:47.847565Z","steps":["trace[9239351] 'range keys from in-memory index tree' (duration: 109.374278ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:03:07.880507Z","caller":"traceutil/trace.go:171","msg":"trace[1842621134] linearizableReadLoop","detail":"{readStateIndex:2639; appliedIndex:2638; }","duration":"274.361207ms","start":"2026-04-16T03:03:07.606124Z","end":"2026-04-16T03:03:07.880485Z","steps":["trace[1842621134] 'read index received' (duration: 274.319576ms)","trace[1842621134] 'applied index is now lower than readState.Index' (duration: 40.481µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T03:03:07.880601Z","caller":"traceutil/trace.go:171","msg":"trace[850809507] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"668.872171ms","start":"2026-04-16T03:03:07.211688Z","end":"2026-04-16T03:03:07.88056Z","steps":["trace[850809507] 'process raft request' (duration: 668.666047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T03:03:07.880737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.590702ms","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-04-16T03:03:07.880765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:03:07.211667Z","time spent":"669.013224ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T03:03:07.880775Z","caller":"traceutil/trace.go:171","msg":"trace[1606562309] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2521; }","duration":"274.675504ms","start":"2026-04-16T03:03:07.606086Z","end":"2026-04-16T03:03:07.880762Z","steps":["trace[1606562309] 'agreement among raft nodes before linearized reading' (duration: 274.537251ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:03:07.970791Z","caller":"traceutil/trace.go:171","msg":"trace[831064251] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"364.367953ms","start":"2026-04-16T03:03:07.6064Z","end":"2026-04-16T03:03:07.970768Z","steps":["trace[831064251] 'process raft request' (duration: 306.620718ms)","trace[831064251] 'compare' (duration: 57.410757ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T03:03:07.970896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T03:03:07.606358Z","time spent":"364.485976ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T03:03:07.970959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.662662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T03:03:07.971039Z","caller":"traceutil/trace.go:171","msg":"trace[890934261] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2523; }","duration":"199.789246ms","start":"2026-04-16T03:03:07.771231Z","end":"2026-04-16T03:03:07.97102Z","steps":["trace[890934261] 'agreement among raft nodes before linearized reading' (duration: 199.647172ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T03:03:08.208003Z","caller":"traceutil/trace.go:171","msg":"trace[502679156] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"169.311945ms","start":"2026-04-16T03:03:08.038544Z","end":"2026-04-16T03:03:08.207856Z","steps":["trace[502679156] 'process raft request' (duration: 149.179193ms)","trace[502679156] 'compare' (duration: 19.853556ms)"],"step_count":2}