{"level":"warn","ts":"2026-03-20T10:08:49.911528Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T10:08:49.91321Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-20T10:08:49.913373Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T10:08:49.913393Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-03-20T10:08:49.913416Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-20T10:08:49.915172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-03-20T10:08:49.915276Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-20T10:08:49.920693Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.166528ms"} {"level":"info","ts":"2026-03-20T10:08:49.93607Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-03-20T10:08:49.936164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T10:08:49.936185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-03-20T10:08:49.936202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T10:08:49.93621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-03-20T10:08:49.936249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-03-20T10:08:49.943474Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T10:08:49.951002Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T10:08:49.954065Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-20T10:08:49.957256Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T10:08:49.959737Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T10:08:49.959947Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T10:08:49.959995Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T10:08:49.960016Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-20T10:08:49.960435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-03-20T10:08:49.960786Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-03-20T10:08:49.962081Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-20T10:08:49.962321Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T10:08:49.962354Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T10:08:49.962429Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-03-20T10:08:49.962444Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-03-20T10:08:50.036811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T10:08:50.03686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T10:08:50.036897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-03-20T10:08:50.036917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-03-20T10:08:50.036928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-03-20T10:08:50.036944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-03-20T10:08:50.036957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-03-20T10:08:50.038681Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T10:08:50.038758Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T10:08:50.038912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T10:08:50.039025Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:08:50.040076Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:08:50.040187Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:08:50.040217Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T10:08:50.040497Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T10:08:50.040531Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T10:08:50.040616Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-03-20T10:08:50.043547Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-20T10:08:53.335343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.190287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:08:53.33545Z","caller":"traceutil/trace.go:171","msg":"trace[811925538] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"184.843257ms","start":"2026-03-20T10:08:53.150591Z","end":"2026-03-20T10:08:53.335434Z","steps":["trace[811925538] 'process raft request' (duration: 69.14145ms)","trace[811925538] 'compare' (duration: 115.079292ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:02.882291Z","caller":"traceutil/trace.go:171","msg":"trace[1094481199] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"889.005991ms","start":"2026-03-20T10:09:01.99326Z","end":"2026-03-20T10:09:02.882266Z","steps":["trace[1094481199] 'process raft request' (duration: 888.844825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:02.882669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:01.99323Z","time spent":"889.152097ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4467,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:02.884132Z","caller":"traceutil/trace.go:171","msg":"trace[1370082007] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:409; }","duration":"311.735362ms","start":"2026-03-20T10:09:02.572376Z","end":"2026-03-20T10:09:02.884112Z","steps":["trace[1370082007] 'read index received' (duration: 309.719713ms)","trace[1370082007] 'applied index is now lower than readState.Index' (duration: 2.014699ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:02.884274Z","caller":"traceutil/trace.go:171","msg":"trace[2129632901] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"568.102695ms","start":"2026-03-20T10:09:02.316159Z","end":"2026-03-20T10:09:02.884261Z","steps":["trace[2129632901] 'process raft request' (duration: 567.327685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:02.8843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.916989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:09:02.884335Z","caller":"traceutil/trace.go:171","msg":"trace[1729652329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"311.967771ms","start":"2026-03-20T10:09:02.572359Z","end":"2026-03-20T10:09:02.884327Z","steps":["trace[1729652329] 'agreement among raft nodes before linearized reading' (duration: 311.874047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:02.884357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:02.572305Z","time spent":"312.046884ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T10:09:02.884422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:02.316138Z","time spent":"568.168198ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:02.884514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.740512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T10:09:02.884536Z","caller":"traceutil/trace.go:171","msg":"trace[1848889941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:396; }","duration":"115.799994ms","start":"2026-03-20T10:09:02.76873Z","end":"2026-03-20T10:09:02.88453Z","steps":["trace[1848889941] 'agreement among raft nodes before linearized reading' (duration: 115.759382ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:09.068954Z","caller":"traceutil/trace.go:171","msg":"trace[2063021108] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"158.029155ms","start":"2026-03-20T10:09:08.910903Z","end":"2026-03-20T10:09:09.068932Z","steps":["trace[2063021108] 'read index received' (duration: 157.940831ms)","trace[2063021108] 'applied index is now lower than readState.Index' (duration: 87.504µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:09:09.069112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.16374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T10:09:09.069055Z","caller":"traceutil/trace.go:171","msg":"trace[1332213156] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"367.357667ms","start":"2026-03-20T10:09:08.70166Z","end":"2026-03-20T10:09:09.069017Z","steps":["trace[1332213156] 'process raft request' (duration: 367.139328ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:09.069159Z","caller":"traceutil/trace.go:171","msg":"trace[1334786882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"158.304205ms","start":"2026-03-20T10:09:08.910844Z","end":"2026-03-20T10:09:09.069148Z","steps":["trace[1334786882] 'agreement among raft nodes before linearized reading' (duration: 158.16848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:09.069273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:08.701615Z","time spent":"367.561684ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:10.590794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506495592s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-20T10:09:10.590969Z","caller":"traceutil/trace.go:171","msg":"trace[1502376777] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"1.521840755s","start":"2026-03-20T10:09:09.06911Z","end":"2026-03-20T10:09:10.59095Z","steps":["trace[1502376777] 'read index received' (duration: 15.430967ms)","trace[1502376777] 'applied index is now lower than readState.Index' (duration: 1.506407988s)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:09:10.591122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.679415552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:09:10.591148Z","caller":"traceutil/trace.go:171","msg":"trace[1191076181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"1.679481925s","start":"2026-03-20T10:09:08.91166Z","end":"2026-03-20T10:09:10.591142Z","steps":["trace[1191076181] 'agreement among raft nodes before linearized reading' (duration: 1.679405412s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:10.591188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:08.911653Z","time spent":"1.679527497s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-20T10:09:10.591285Z","caller":"traceutil/trace.go:171","msg":"trace[1794973150] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.516443144s","start":"2026-03-20T10:09:09.074828Z","end":"2026-03-20T10:09:10.591271Z","steps":["trace[1794973150] 'process raft request' (duration: 1.516086529s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:10.591438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:09.074803Z","time spent":"1.516525537s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:10.591451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221919385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:10.591493Z","caller":"traceutil/trace.go:171","msg":"trace[657560597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"1.222004869s","start":"2026-03-20T10:09:09.369482Z","end":"2026-03-20T10:09:10.591487Z","steps":["trace[657560597] 'agreement among raft nodes before linearized reading' (duration: 1.221932856s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:10.591512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:09.369461Z","time spent":"1.222044421s","remote":"127.0.0.1:53980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T10:09:10.591647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.817183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:10.591669Z","caller":"traceutil/trace.go:171","msg":"trace[275811706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"508.876285ms","start":"2026-03-20T10:09:10.082787Z","end":"2026-03-20T10:09:10.591664Z","steps":["trace[275811706] 'agreement among raft nodes before linearized reading' (duration: 508.842884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:10.591684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:10.082768Z","time spent":"508.912417ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T10:09:10.591898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.76535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-20T10:09:10.591935Z","caller":"traceutil/trace.go:171","msg":"trace[851455551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"941.847513ms","start":"2026-03-20T10:09:09.650076Z","end":"2026-03-20T10:09:10.591924Z","steps":["trace[851455551] 'agreement among raft nodes before linearized reading' (duration: 941.783111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:10.591972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:09.650058Z","time spent":"941.904106ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T10:09:10.5919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136635498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-20T10:09:10.592107Z","caller":"traceutil/trace.go:171","msg":"trace[1174294559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"1.136850317s","start":"2026-03-20T10:09:09.45524Z","end":"2026-03-20T10:09:10.59209Z","steps":["trace[1174294559] 'agreement among raft nodes before linearized reading' (duration: 1.136597577s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:10.592142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:09.455194Z","time spent":"1.136939259s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T10:09:10.591858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.300947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-03-20T10:09:10.593777Z","caller":"traceutil/trace.go:171","msg":"trace[2027191366] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:416; }","duration":"844.238693ms","start":"2026-03-20T10:09:09.749526Z","end":"2026-03-20T10:09:10.593765Z","steps":["trace[2027191366] 'agreement among raft nodes before linearized reading' (duration: 842.303137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:10.593801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:09.749512Z","time spent":"844.280814ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-20T10:09:10.71964Z","caller":"traceutil/trace.go:171","msg":"trace[1942465910] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"124.382747ms","start":"2026-03-20T10:09:10.595232Z","end":"2026-03-20T10:09:10.719615Z","steps":["trace[1942465910] 'process raft request' (duration: 118.458775ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:10.723434Z","caller":"traceutil/trace.go:171","msg":"trace[1663429868] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:433; }","duration":"124.693531ms","start":"2026-03-20T10:09:10.598722Z","end":"2026-03-20T10:09:10.723416Z","steps":["trace[1663429868] 'read index received' (duration: 114.937627ms)","trace[1663429868] 'applied index is now lower than readState.Index' (duration: 9.754814ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:09:10.723565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.819805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:10.723598Z","caller":"traceutil/trace.go:171","msg":"trace[1805311907] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"127.174608ms","start":"2026-03-20T10:09:10.596403Z","end":"2026-03-20T10:09:10.723578Z","steps":["trace[1805311907] 'process raft request' (duration: 126.900427ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:10.723685Z","caller":"traceutil/trace.go:171","msg":"trace[1034044532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"124.897708ms","start":"2026-03-20T10:09:10.598696Z","end":"2026-03-20T10:09:10.723594Z","steps":["trace[1034044532] 'agreement among raft nodes before linearized reading' (duration: 124.821606ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:10.723749Z","caller":"traceutil/trace.go:171","msg":"trace[152311341] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"128.471448ms","start":"2026-03-20T10:09:10.59521Z","end":"2026-03-20T10:09:10.723681Z","steps":["trace[152311341] 'process raft request' (duration: 128.01444ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:10.724013Z","caller":"traceutil/trace.go:171","msg":"trace[1302086804] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"115.807151ms","start":"2026-03-20T10:09:10.608193Z","end":"2026-03-20T10:09:10.724Z","steps":["trace[1302086804] 'process raft request' (duration: 115.156816ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:12.477686Z","caller":"traceutil/trace.go:171","msg":"trace[1196327111] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"250.7867ms","start":"2026-03-20T10:09:12.226869Z","end":"2026-03-20T10:09:12.477656Z","steps":["trace[1196327111] 'process raft request' (duration: 250.571122ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:12.648901Z","caller":"traceutil/trace.go:171","msg":"trace[468496719] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"164.556994ms","start":"2026-03-20T10:09:12.484316Z","end":"2026-03-20T10:09:12.648873Z","steps":["trace[468496719] 'process raft request' (duration: 87.994636ms)","trace[468496719] 'compare' (duration: 76.167062ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:09:12.907699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.515053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:09:12.907961Z","caller":"traceutil/trace.go:171","msg":"trace[1711709014] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"254.755234ms","start":"2026-03-20T10:09:12.653166Z","end":"2026-03-20T10:09:12.907921Z","steps":["trace[1711709014] 'process raft request' (duration: 102.519054ms)","trace[1711709014] 'compare' (duration: 151.347905ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:12.908045Z","caller":"traceutil/trace.go:171","msg":"trace[418736539] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:440; }","duration":"184.665351ms","start":"2026-03-20T10:09:12.723353Z","end":"2026-03-20T10:09:12.908018Z","steps":["trace[418736539] 'read index received' (duration: 32.267903ms)","trace[418736539] 'applied index is now lower than readState.Index' (duration: 152.396138ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:12.908145Z","caller":"traceutil/trace.go:171","msg":"trace[1417524164] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"253.488095ms","start":"2026-03-20T10:09:12.654627Z","end":"2026-03-20T10:09:12.908115Z","steps":["trace[1417524164] 'process raft request' (duration: 253.302277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:12.908225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.859478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:09:12.908215Z","caller":"traceutil/trace.go:171","msg":"trace[389744578] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"254.871979ms","start":"2026-03-20T10:09:12.653311Z","end":"2026-03-20T10:09:12.908183Z","steps":["trace[389744578] 'process raft request' (duration: 254.539045ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:12.908262Z","caller":"traceutil/trace.go:171","msg":"trace[785086821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"184.90691ms","start":"2026-03-20T10:09:12.723343Z","end":"2026-03-20T10:09:12.90825Z","steps":["trace[785086821] 'agreement among raft nodes before linearized reading' (duration: 184.817426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:12.908603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.332961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-20T10:09:12.908619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.311509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-20T10:09:12.908657Z","caller":"traceutil/trace.go:171","msg":"trace[458751156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"181.416284ms","start":"2026-03-20T10:09:12.727226Z","end":"2026-03-20T10:09:12.908642Z","steps":["trace[458751156] 'agreement among raft nodes before linearized reading' (duration: 181.292119ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:12.908667Z","caller":"traceutil/trace.go:171","msg":"trace[941385874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"181.412483ms","start":"2026-03-20T10:09:12.727242Z","end":"2026-03-20T10:09:12.908655Z","steps":["trace[941385874] 'agreement among raft nodes before linearized reading' (duration: 181.285888ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.145403Z","caller":"traceutil/trace.go:171","msg":"trace[535014791] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"231.730238ms","start":"2026-03-20T10:09:12.913641Z","end":"2026-03-20T10:09:13.145371Z","steps":["trace[535014791] 'process raft request' (duration: 139.650575ms)","trace[535014791] 'compare' (duration: 91.845784ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:13.14556Z","caller":"traceutil/trace.go:171","msg":"trace[930650551] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"230.730679ms","start":"2026-03-20T10:09:12.914794Z","end":"2026-03-20T10:09:13.145525Z","steps":["trace[930650551] 'process raft request' (duration: 230.49838ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.145752Z","caller":"traceutil/trace.go:171","msg":"trace[1241460074] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"229.857456ms","start":"2026-03-20T10:09:12.915884Z","end":"2026-03-20T10:09:13.145741Z","steps":["trace[1241460074] 'process raft request' (duration: 229.477721ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.145958Z","caller":"traceutil/trace.go:171","msg":"trace[11907474] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"229.732951ms","start":"2026-03-20T10:09:12.916189Z","end":"2026-03-20T10:09:13.145922Z","steps":["trace[11907474] 'process raft request' (duration: 229.429419ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.146101Z","caller":"traceutil/trace.go:171","msg":"trace[271822756] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"229.668309ms","start":"2026-03-20T10:09:12.916411Z","end":"2026-03-20T10:09:13.14608Z","steps":["trace[271822756] 'process raft request' (duration: 229.335825ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.146242Z","caller":"traceutil/trace.go:171","msg":"trace[1753583566] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"229.721451ms","start":"2026-03-20T10:09:12.916508Z","end":"2026-03-20T10:09:13.14623Z","steps":["trace[1753583566] 'process raft request' (duration: 229.4643ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.146264Z","caller":"traceutil/trace.go:171","msg":"trace[182591779] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"227.168771ms","start":"2026-03-20T10:09:12.919076Z","end":"2026-03-20T10:09:13.146245Z","steps":["trace[182591779] 'process raft request' (duration: 227.026446ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.161298Z","caller":"traceutil/trace.go:171","msg":"trace[1472286206] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"118.623841ms","start":"2026-03-20T10:09:13.042656Z","end":"2026-03-20T10:09:13.161279Z","steps":["trace[1472286206] 'process raft request' (duration: 118.505316ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.33266Z","caller":"traceutil/trace.go:171","msg":"trace[198101785] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"181.749811ms","start":"2026-03-20T10:09:13.150871Z","end":"2026-03-20T10:09:13.332621Z","steps":["trace[198101785] 'process raft request' (duration: 166.192933ms)","trace[198101785] 'compare' (duration: 15.318668ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:09:13.332798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.358928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-03-20T10:09:13.33284Z","caller":"traceutil/trace.go:171","msg":"trace[1052916058] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:437; }","duration":"177.440452ms","start":"2026-03-20T10:09:13.155389Z","end":"2026-03-20T10:09:13.332829Z","steps":["trace[1052916058] 'agreement among raft nodes before linearized reading' (duration: 177.323677ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.333022Z","caller":"traceutil/trace.go:171","msg":"trace[1475730243] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"177.962393ms","start":"2026-03-20T10:09:13.155048Z","end":"2026-03-20T10:09:13.333011Z","steps":["trace[1475730243] 'process raft request' (duration: 177.493605ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.332671Z","caller":"traceutil/trace.go:171","msg":"trace[1548536550] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:452; }","duration":"170.97975ms","start":"2026-03-20T10:09:13.161671Z","end":"2026-03-20T10:09:13.332651Z","steps":["trace[1548536550] 'read index received' (duration: 155.536046ms)","trace[1548536550] 'applied index is now lower than readState.Index' (duration: 15.442874ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:13.342789Z","caller":"traceutil/trace.go:171","msg":"trace[1576865568] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"175.701104ms","start":"2026-03-20T10:09:13.167068Z","end":"2026-03-20T10:09:13.34277Z","steps":["trace[1576865568] 'process raft request' (duration: 175.58364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:13.604241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.484683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:09:13.604658Z","caller":"traceutil/trace.go:171","msg":"trace[292883080] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"228.043452ms","start":"2026-03-20T10:09:13.376597Z","end":"2026-03-20T10:09:13.60464Z","steps":["trace[292883080] 'process raft request' (duration: 227.977929ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.604973Z","caller":"traceutil/trace.go:171","msg":"trace[1324898753] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"230.373534ms","start":"2026-03-20T10:09:13.374553Z","end":"2026-03-20T10:09:13.604927Z","steps":["trace[1324898753] 'process raft request' (duration: 229.800112ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.605287Z","caller":"traceutil/trace.go:171","msg":"trace[172539102] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"230.763989ms","start":"2026-03-20T10:09:13.374493Z","end":"2026-03-20T10:09:13.605257Z","steps":["trace[172539102] 'process raft request' (duration: 63.200773ms)","trace[172539102] 'compare' (duration: 166.341438ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:13.60859Z","caller":"traceutil/trace.go:171","msg":"trace[610122080] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"227.480081ms","start":"2026-03-20T10:09:13.381074Z","end":"2026-03-20T10:09:13.608554Z","steps":["trace[610122080] 'process raft request' (duration: 227.257773ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:13.608595Z","caller":"traceutil/trace.go:171","msg":"trace[1163607428] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"225.481253ms","start":"2026-03-20T10:09:13.383084Z","end":"2026-03-20T10:09:13.608565Z","steps":["trace[1163607428] 'process raft request' (duration: 225.394879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:16.855543Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.703128192s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T10:09:16.856068Z","caller":"traceutil/trace.go:171","msg":"trace[1103921887] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"1.703712475s","start":"2026-03-20T10:09:15.152333Z","end":"2026-03-20T10:09:16.856046Z","steps":["trace[1103921887] 'process raft request' (duration: 1.703402043s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:16.856248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:15.152322Z","time spent":"1.703794578s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:16.860344Z","caller":"traceutil/trace.go:171","msg":"trace[832288581] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"1.706739392s","start":"2026-03-20T10:09:15.1536Z","end":"2026-03-20T10:09:16.860339Z","steps":["trace[832288581] 'process raft request' (duration: 1.704861119s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:16.860382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:15.153572Z","time spent":"1.706789815s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:16.860559Z","caller":"traceutil/trace.go:171","msg":"trace[1463055518] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"1.706395219s","start":"2026-03-20T10:09:15.154153Z","end":"2026-03-20T10:09:16.860548Z","steps":["trace[1463055518] 'process raft request' (duration: 1.706001454s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:16.860622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:15.154144Z","time spent":"1.706442921s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:16.860563Z","caller":"traceutil/trace.go:171","msg":"trace[1439078908] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"624.714902ms","start":"2026-03-20T10:09:16.235841Z","end":"2026-03-20T10:09:16.860556Z","steps":["trace[1439078908] 'process raft request' (duration: 624.409701ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:16.860282Z","caller":"traceutil/trace.go:171","msg":"trace[1566529427] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:475; }","duration":"493.03092ms","start":"2026-03-20T10:09:16.367242Z","end":"2026-03-20T10:09:16.860273Z","steps":["trace[1566529427] 'read index received' (duration: 488.543086ms)","trace[1566529427] 'applied index is now lower than readState.Index' (duration: 4.487284ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:09:16.860711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:16.235819Z","time spent":"624.859398ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:16.860758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.00688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T10:09:16.860787Z","caller":"traceutil/trace.go:171","msg":"trace[511062235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"169.068462ms","start":"2026-03-20T10:09:16.69171Z","end":"2026-03-20T10:09:16.860779Z","steps":["trace[511062235] 'agreement among raft nodes before linearized reading' (duration: 169.025919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:16.860664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.422376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:16.860879Z","caller":"traceutil/trace.go:171","msg":"trace[1928207365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"493.637766ms","start":"2026-03-20T10:09:16.367234Z","end":"2026-03-20T10:09:16.860872Z","steps":["trace[1928207365] 'agreement among raft nodes before linearized reading' (duration: 493.407536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:16.860903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:16.367197Z","time spent":"493.700678ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T10:09:27.134279Z","caller":"traceutil/trace.go:171","msg":"trace[628744364] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"185.719678ms","start":"2026-03-20T10:09:26.948537Z","end":"2026-03-20T10:09:27.134257Z","steps":["trace[628744364] 'read index received' (duration: 185.614414ms)","trace[628744364] 'applied index is now lower than readState.Index' (duration: 104.184µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:27.134349Z","caller":"traceutil/trace.go:171","msg":"trace[1552099663] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"192.665377ms","start":"2026-03-20T10:09:26.941647Z","end":"2026-03-20T10:09:27.134313Z","steps":["trace[1552099663] 'process raft request' (duration: 192.48629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:27.134758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.121453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:09:27.134913Z","caller":"traceutil/trace.go:171","msg":"trace[1965686840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"186.356682ms","start":"2026-03-20T10:09:26.948506Z","end":"2026-03-20T10:09:27.134863Z","steps":["trace[1965686840] 'agreement among raft nodes before linearized reading' (duration: 185.837042ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:27.139071Z","caller":"traceutil/trace.go:171","msg":"trace[293930341] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"141.21455ms","start":"2026-03-20T10:09:26.997833Z","end":"2026-03-20T10:09:27.139047Z","steps":["trace[293930341] 'process raft request' (duration: 141.037264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:27.139095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.381928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-20T10:09:27.139299Z","caller":"traceutil/trace.go:171","msg":"trace[1245577707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"190.605227ms","start":"2026-03-20T10:09:26.948672Z","end":"2026-03-20T10:09:27.139277Z","steps":["trace[1245577707] 'agreement among raft nodes before linearized reading' (duration: 190.308455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:27.139443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.26374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T10:09:27.139478Z","caller":"traceutil/trace.go:171","msg":"trace[1813586462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"186.325372ms","start":"2026-03-20T10:09:26.953144Z","end":"2026-03-20T10:09:27.139469Z","steps":["trace[1813586462] 'agreement among raft nodes before linearized reading' (duration: 186.252479ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:33.371581Z","caller":"traceutil/trace.go:171","msg":"trace[86807050] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"179.813441ms","start":"2026-03-20T10:09:33.191737Z","end":"2026-03-20T10:09:33.371551Z","steps":["trace[86807050] 'read index received' (duration: 179.507419ms)","trace[86807050] 'applied index is now lower than readState.Index' (duration: 305.132µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:33.371736Z","caller":"traceutil/trace.go:171","msg":"trace[195300390] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"180.850921ms","start":"2026-03-20T10:09:33.190839Z","end":"2026-03-20T10:09:33.37169Z","steps":["trace[195300390] 'process raft request' (duration: 180.490967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:33.371855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.013399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T10:09:33.3719Z","caller":"traceutil/trace.go:171","msg":"trace[195063056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"180.092521ms","start":"2026-03-20T10:09:33.191792Z","end":"2026-03-20T10:09:33.371884Z","steps":["trace[195063056] 'agreement among raft nodes before linearized reading' (duration: 179.924715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:33.371913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.156754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-20T10:09:33.372018Z","caller":"traceutil/trace.go:171","msg":"trace[681651757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"180.281619ms","start":"2026-03-20T10:09:33.191697Z","end":"2026-03-20T10:09:33.371978Z","steps":["trace[681651757] 'agreement among raft nodes before linearized reading' (duration: 180.022639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:33.372056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.705421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-20T10:09:33.372085Z","caller":"traceutil/trace.go:171","msg":"trace[1448389632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"175.736522ms","start":"2026-03-20T10:09:33.19634Z","end":"2026-03-20T10:09:33.372077Z","steps":["trace[1448389632] 'agreement among raft nodes before linearized reading' (duration: 175.660239ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:34.073424Z","caller":"traceutil/trace.go:171","msg":"trace[1286369128] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"163.718898ms","start":"2026-03-20T10:09:33.909679Z","end":"2026-03-20T10:09:34.073398Z","steps":["trace[1286369128] 'read index received' (duration: 163.572702ms)","trace[1286369128] 'applied index is now lower than readState.Index' (duration: 145.076µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:34.073469Z","caller":"traceutil/trace.go:171","msg":"trace[692429703] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"299.359429ms","start":"2026-03-20T10:09:33.774083Z","end":"2026-03-20T10:09:34.073443Z","steps":["trace[692429703] 'process raft request' (duration: 299.181912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:34.07356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.878833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T10:09:34.0736Z","caller":"traceutil/trace.go:171","msg":"trace[16494845] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:559; }","duration":"163.968407ms","start":"2026-03-20T10:09:33.909621Z","end":"2026-03-20T10:09:34.07359Z","steps":["trace[16494845] 'agreement among raft nodes before linearized reading' (duration: 163.890034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:34.230105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.961739ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:34.230341Z","caller":"traceutil/trace.go:171","msg":"trace[1734646900] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:559; }","duration":"108.207078ms","start":"2026-03-20T10:09:34.122106Z","end":"2026-03-20T10:09:34.230313Z","steps":["trace[1734646900] 'range keys from in-memory index tree' (duration: 107.943757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:35.324846Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.146822254s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T10:09:35.325276Z","caller":"traceutil/trace.go:171","msg":"trace[1902879229] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.238353995s","start":"2026-03-20T10:09:34.086898Z","end":"2026-03-20T10:09:35.325252Z","steps":["trace[1902879229] 'process raft request' (duration: 1.238133517s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:35.325459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:34.086868Z","time spent":"1.238510642s","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:35.353831Z","caller":"traceutil/trace.go:171","msg":"trace[910936775] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.122557545s","start":"2026-03-20T10:09:34.231249Z","end":"2026-03-20T10:09:35.353807Z","steps":["trace[910936775] 'process raft request' (duration: 1.122344807s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:35.353992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:34.231224Z","time spent":"1.12268201s","remote":"127.0.0.1:54002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:35.883024Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959272724010181,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T10:09:36.384171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959272724010181,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T10:09:36.744533Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.366977309s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T10:09:36.744932Z","caller":"traceutil/trace.go:171","msg":"trace[1790406846] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"1.361961484s","start":"2026-03-20T10:09:35.38295Z","end":"2026-03-20T10:09:36.744911Z","steps":["trace[1790406846] 'read index received' (duration: 1.361727316s)","trace[1790406846] 'applied index is now lower than readState.Index' (duration: 233.258µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:36.745016Z","caller":"traceutil/trace.go:171","msg":"trace[1266340236] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.36752602s","start":"2026-03-20T10:09:35.377474Z","end":"2026-03-20T10:09:36.745Z","steps":["trace[1266340236] 'process raft request' (duration: 1.367243789s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:36.74513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:35.377445Z","time spent":"1.367608593s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:36.745236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.362250335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-20T10:09:36.745213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361638132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-20T10:09:36.745273Z","caller":"traceutil/trace.go:171","msg":"trace[1232646417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"1.36233397s","start":"2026-03-20T10:09:35.382928Z","end":"2026-03-20T10:09:36.745261Z","steps":["trace[1232646417] 'agreement among raft nodes before linearized reading' (duration: 1.36210704s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:36.745304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.314953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:36.745302Z","caller":"traceutil/trace.go:171","msg":"trace[1589353231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"1.361757098s","start":"2026-03-20T10:09:35.383525Z","end":"2026-03-20T10:09:36.745282Z","steps":["trace[1589353231] 'agreement among raft nodes before linearized reading' (duration: 1.361542788s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:36.745336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:35.382918Z","time spent":"1.362408272s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-20T10:09:36.745341Z","caller":"traceutil/trace.go:171","msg":"trace[2139846226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"375.378265ms","start":"2026-03-20T10:09:36.369949Z","end":"2026-03-20T10:09:36.745327Z","steps":["trace[2139846226] 'agreement among raft nodes before linearized reading' (duration: 375.336274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:36.745348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:35.383517Z","time spent":"1.36182257s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T10:09:36.745379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:36.369931Z","time spent":"375.443308ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T10:09:36.74523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36106283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T10:09:36.745459Z","caller":"traceutil/trace.go:171","msg":"trace[1636401674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"1.36130773s","start":"2026-03-20T10:09:35.384126Z","end":"2026-03-20T10:09:36.745434Z","steps":["trace[1636401674] 'agreement among raft nodes before linearized reading' (duration: 1.360997237s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:36.745537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:35.384121Z","time spent":"1.361390403s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T10:09:38.119272Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.369638578s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-20T10:09:38.484989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.469953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:09:38.485243Z","caller":"traceutil/trace.go:171","msg":"trace[613526755] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:584; }","duration":"116.811065ms","start":"2026-03-20T10:09:38.368415Z","end":"2026-03-20T10:09:38.485226Z","steps":["trace[613526755] 'read index received' (duration: 40.63834ms)","trace[613526755] 'applied index is now lower than readState.Index' (duration: 76.171794ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:09:38.485328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.915128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:38.485361Z","caller":"traceutil/trace.go:171","msg":"trace[756594391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"116.97631ms","start":"2026-03-20T10:09:38.368376Z","end":"2026-03-20T10:09:38.485352Z","steps":["trace[756594391] 'agreement among raft nodes before linearized reading' (duration: 116.907018ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:38.485589Z","caller":"traceutil/trace.go:171","msg":"trace[632835122] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.735775527s","start":"2026-03-20T10:09:36.749802Z","end":"2026-03-20T10:09:38.485578Z","steps":["trace[632835122] 'process raft request' (duration: 1.735357611s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:38.485656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:36.749794Z","time spent":"1.735828299s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:38.485792Z","caller":"traceutil/trace.go:171","msg":"trace[1549793560] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.25168748s","start":"2026-03-20T10:09:37.234075Z","end":"2026-03-20T10:09:38.485763Z","steps":["trace[1549793560] 'process raft request' (duration: 1.251114378s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:38.485939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:37.234059Z","time spent":"1.251800604s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:38.485959Z","caller":"traceutil/trace.go:171","msg":"trace[453944127] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.736365089s","start":"2026-03-20T10:09:36.749561Z","end":"2026-03-20T10:09:38.485926Z","steps":["trace[453944127] 'process raft request' (duration: 1.369880467s)","trace[453944127] 'compare' (duration: 365.324447ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:38.486038Z","caller":"traceutil/trace.go:171","msg":"trace[977069020] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.736278786s","start":"2026-03-20T10:09:36.749742Z","end":"2026-03-20T10:09:38.48602Z","steps":["trace[977069020] 'process raft request' (duration: 1.735369591s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:38.486049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:36.749472Z","time spent":"1.736531215s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:38.486128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:36.749729Z","time spent":"1.736356489s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:40.701262Z","caller":"traceutil/trace.go:171","msg":"trace[487273235] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"331.972971ms","start":"2026-03-20T10:09:40.369266Z","end":"2026-03-20T10:09:40.701239Z","steps":["trace[487273235] 'read index received' (duration: 328.190824ms)","trace[487273235] 'applied index is now lower than readState.Index' (duration: 3.781117ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:40.701372Z","caller":"traceutil/trace.go:171","msg":"trace[204320816] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"477.641097ms","start":"2026-03-20T10:09:40.223722Z","end":"2026-03-20T10:09:40.701363Z","steps":["trace[204320816] 'process raft request' (duration: 473.705664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:40.701446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:40.223712Z","time spent":"477.684008ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T10:09:40.701607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.518927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-20T10:09:40.701661Z","caller":"traceutil/trace.go:171","msg":"trace[1098257102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"212.61379ms","start":"2026-03-20T10:09:40.48903Z","end":"2026-03-20T10:09:40.701643Z","steps":["trace[1098257102] 'agreement among raft nodes before linearized reading' (duration: 212.415373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:40.701802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.765908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T10:09:40.701839Z","caller":"traceutil/trace.go:171","msg":"trace[350417126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"211.847201ms","start":"2026-03-20T10:09:40.489982Z","end":"2026-03-20T10:09:40.701829Z","steps":["trace[350417126] 'agreement among raft nodes before linearized reading' (duration: 211.734167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:40.701908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.617515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:40.701988Z","caller":"traceutil/trace.go:171","msg":"trace[42415973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"332.730659ms","start":"2026-03-20T10:09:40.36923Z","end":"2026-03-20T10:09:40.701961Z","steps":["trace[42415973] 'agreement among raft nodes before linearized reading' (duration: 332.587334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:40.702032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:40.369213Z","time spent":"332.810483ms","remote":"127.0.0.1:53980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T10:09:40.702065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.128312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:09:40.702111Z","caller":"traceutil/trace.go:171","msg":"trace[531877247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"212.201915ms","start":"2026-03-20T10:09:40.489896Z","end":"2026-03-20T10:09:40.702098Z","steps":["trace[531877247] 'agreement among raft nodes before linearized reading' (duration: 211.868642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:43.599001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:43.599091Z","caller":"traceutil/trace.go:171","msg":"trace[1359560961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"229.766616ms","start":"2026-03-20T10:09:43.369306Z","end":"2026-03-20T10:09:43.599072Z","steps":["trace[1359560961] 'range keys from in-memory index tree' (duration: 229.542647ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:46.208404Z","caller":"traceutil/trace.go:171","msg":"trace[2028928643] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"287.100659ms","start":"2026-03-20T10:09:45.921287Z","end":"2026-03-20T10:09:46.208388Z","steps":["trace[2028928643] 'read index received' (duration: 287.005395ms)","trace[2028928643] 'applied index is now lower than readState.Index' (duration: 94.704µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:46.208476Z","caller":"traceutil/trace.go:171","msg":"trace[570773384] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"301.719743ms","start":"2026-03-20T10:09:45.906729Z","end":"2026-03-20T10:09:46.208449Z","steps":["trace[570773384] 'process raft request' (duration: 301.567966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:46.208536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.228475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:46.208551Z","caller":"traceutil/trace.go:171","msg":"trace[1791294760] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:667; }","duration":"287.269737ms","start":"2026-03-20T10:09:45.921276Z","end":"2026-03-20T10:09:46.208546Z","steps":["trace[1791294760] 'agreement among raft nodes before linearized reading' (duration: 287.249116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:46.20861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:45.906703Z","time spent":"301.834298ms","remote":"127.0.0.1:54124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4582,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:46.208773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.417234ms","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-03-20T10:09:46.208793Z","caller":"traceutil/trace.go:171","msg":"trace[619159122] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:667; }","duration":"258.466336ms","start":"2026-03-20T10:09:45.95032Z","end":"2026-03-20T10:09:46.208786Z","steps":["trace[619159122] 'agreement among raft nodes before linearized reading' (duration: 258.439135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:46.208816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.292599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T10:09:46.208868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.757737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:46.208874Z","caller":"traceutil/trace.go:171","msg":"trace[138166965] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:667; }","duration":"258.366642ms","start":"2026-03-20T10:09:45.950491Z","end":"2026-03-20T10:09:46.208858Z","steps":["trace[138166965] 'agreement among raft nodes before linearized reading' (duration: 258.258268ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:46.208892Z","caller":"traceutil/trace.go:171","msg":"trace[1979391390] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:667; }","duration":"258.801019ms","start":"2026-03-20T10:09:45.950078Z","end":"2026-03-20T10:09:46.208879Z","steps":["trace[1979391390] 'agreement among raft nodes before linearized reading' (duration: 258.775408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:46.208504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.197423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:46.208932Z","caller":"traceutil/trace.go:171","msg":"trace[1730346908] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:667; }","duration":"287.654131ms","start":"2026-03-20T10:09:45.921262Z","end":"2026-03-20T10:09:46.208916Z","steps":["trace[1730346908] 'agreement among raft nodes before linearized reading' (duration: 287.203194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:46.209088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.756235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:46.209185Z","caller":"traceutil/trace.go:171","msg":"trace[1186934595] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:667; }","duration":"107.899871ms","start":"2026-03-20T10:09:46.101271Z","end":"2026-03-20T10:09:46.209171Z","steps":["trace[1186934595] 'agreement among raft nodes before linearized reading' (duration: 107.772126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:46.209238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.897981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:46.209255Z","caller":"traceutil/trace.go:171","msg":"trace[2127060236] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:667; }","duration":"287.916202ms","start":"2026-03-20T10:09:45.921334Z","end":"2026-03-20T10:09:46.20925Z","steps":["trace[2127060236] 'agreement among raft nodes before linearized reading' (duration: 287.61682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:46.209407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.241343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:46.20945Z","caller":"traceutil/trace.go:171","msg":"trace[124510500] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:667; }","duration":"108.342558ms","start":"2026-03-20T10:09:46.101095Z","end":"2026-03-20T10:09:46.209438Z","steps":["trace[124510500] 'agreement among raft nodes before linearized reading' (duration: 108.271725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:46.441752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.049005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:09:46.441891Z","caller":"traceutil/trace.go:171","msg":"trace[894871430] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"232.755714ms","start":"2026-03-20T10:09:46.209109Z","end":"2026-03-20T10:09:46.441864Z","steps":["trace[894871430] 'process raft request' (duration: 89.525942ms)","trace[894871430] 'compare' (duration: 142.930791ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:46.682623Z","caller":"traceutil/trace.go:171","msg":"trace[1047509868] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"234.388368ms","start":"2026-03-20T10:09:46.448209Z","end":"2026-03-20T10:09:46.682597Z","steps":["trace[1047509868] 'process raft request' (duration: 208.873454ms)","trace[1047509868] 'compare' (duration: 25.40949ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:46.75779Z","caller":"traceutil/trace.go:171","msg":"trace[1518754806] transaction","detail":"{read_only:false; number_of_response:0; response_revision:669; }","duration":"309.540315ms","start":"2026-03-20T10:09:46.448235Z","end":"2026-03-20T10:09:46.757775Z","steps":["trace[1518754806] 'process raft request' (duration: 309.463003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:46.759325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:46.448228Z","time spent":"309.618128ms","remote":"127.0.0.1:40158","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T10:09:46.866581Z","caller":"traceutil/trace.go:171","msg":"trace[856988039] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"104.347673ms","start":"2026-03-20T10:09:46.762213Z","end":"2026-03-20T10:09:46.86656Z","steps":["trace[856988039] 'process raft request' (duration: 96.191849ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:47.070303Z","caller":"traceutil/trace.go:171","msg":"trace[1293858874] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"307.831358ms","start":"2026-03-20T10:09:46.762456Z","end":"2026-03-20T10:09:47.070288Z","steps":["trace[1293858874] 'process raft request' (duration: 307.781406ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:47.070341Z","caller":"traceutil/trace.go:171","msg":"trace[1611769537] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"307.842399ms","start":"2026-03-20T10:09:46.762466Z","end":"2026-03-20T10:09:47.070308Z","steps":["trace[1611769537] 'process raft request' (duration: 306.541369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:47.070408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:46.762432Z","time spent":"307.921361ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:47.070505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:46.762455Z","time spent":"307.960113ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:47.07043Z","caller":"traceutil/trace.go:171","msg":"trace[1470057863] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"307.87911ms","start":"2026-03-20T10:09:46.762521Z","end":"2026-03-20T10:09:47.0704Z","steps":["trace[1470057863] 'process raft request' (duration: 307.743585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:47.070741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:46.762513Z","time spent":"308.128089ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:09:47.070966Z","caller":"traceutil/trace.go:171","msg":"trace[1244595246] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:694; }","duration":"270.286049ms","start":"2026-03-20T10:09:46.800649Z","end":"2026-03-20T10:09:47.070935Z","steps":["trace[1244595246] 'read index received' (duration: 57.765587ms)","trace[1244595246] 'applied index is now lower than readState.Index' (duration: 212.518392ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:09:47.071152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.479217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-20T10:09:47.071199Z","caller":"traceutil/trace.go:171","msg":"trace[2049065507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"270.579581ms","start":"2026-03-20T10:09:46.800605Z","end":"2026-03-20T10:09:47.071184Z","steps":["trace[2049065507] 'agreement among raft nodes before linearized reading' (duration: 270.407934ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:47.275111Z","caller":"traceutil/trace.go:171","msg":"trace[1323652278] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"197.542973ms","start":"2026-03-20T10:09:47.077541Z","end":"2026-03-20T10:09:47.275083Z","steps":["trace[1323652278] 'process raft request' (duration: 134.721322ms)","trace[1323652278] 'compare' (duration: 62.555211ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:47.477408Z","caller":"traceutil/trace.go:171","msg":"trace[1971814472] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"166.487637ms","start":"2026-03-20T10:09:47.31088Z","end":"2026-03-20T10:09:47.477367Z","steps":["trace[1971814472] 'process raft request' (duration: 161.946373ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:51.407589Z","caller":"traceutil/trace.go:171","msg":"trace[387001349] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"156.84038ms","start":"2026-03-20T10:09:51.250731Z","end":"2026-03-20T10:09:51.407571Z","steps":["trace[387001349] 'process raft request' (duration: 156.761907ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:51.407596Z","caller":"traceutil/trace.go:171","msg":"trace[677357067] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"160.202309ms","start":"2026-03-20T10:09:51.247365Z","end":"2026-03-20T10:09:51.407567Z","steps":["trace[677357067] 'process raft request' (duration: 156.60052ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:51.408507Z","caller":"traceutil/trace.go:171","msg":"trace[2143158265] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"157.514685ms","start":"2026-03-20T10:09:51.25098Z","end":"2026-03-20T10:09:51.408495Z","steps":["trace[2143158265] 'process raft request' (duration: 156.549527ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:51.409054Z","caller":"traceutil/trace.go:171","msg":"trace[1277638370] transaction","detail":"{read_only:false; number_of_response:1; response_revision:722; }","duration":"157.926591ms","start":"2026-03-20T10:09:51.251103Z","end":"2026-03-20T10:09:51.409029Z","steps":["trace[1277638370] 'process raft request' (duration: 156.689073ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:09:59.091786Z","caller":"traceutil/trace.go:171","msg":"trace[1809955901] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"270.659039ms","start":"2026-03-20T10:09:58.821106Z","end":"2026-03-20T10:09:59.091765Z","steps":["trace[1809955901] 'read index received' (duration: 270.575335ms)","trace[1809955901] 'applied index is now lower than readState.Index' (duration: 82.913µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:09:59.091877Z","caller":"traceutil/trace.go:171","msg":"trace[1342453114] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"320.36499ms","start":"2026-03-20T10:09:58.771505Z","end":"2026-03-20T10:09:59.09187Z","steps":["trace[1342453114] 'process raft request' (duration: 320.139501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:59.091959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:09:58.771477Z","time spent":"320.419042ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:09:59.092074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.96642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:09:59.092099Z","caller":"traceutil/trace.go:171","msg":"trace[239117681] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:767; }","duration":"271.048243ms","start":"2026-03-20T10:09:58.821043Z","end":"2026-03-20T10:09:59.092092Z","steps":["trace[239117681] 'agreement among raft nodes before linearized reading' (duration: 271.008381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:09:59.0922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.745772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T10:09:59.09263Z","caller":"traceutil/trace.go:171","msg":"trace[1775951008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:767; }","duration":"161.206999ms","start":"2026-03-20T10:09:58.931391Z","end":"2026-03-20T10:09:59.092598Z","steps":["trace[1775951008] 'agreement among raft nodes before linearized reading' (duration: 160.632426ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:10:03.325978Z","caller":"traceutil/trace.go:171","msg":"trace[1679634545] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"121.659795ms","start":"2026-03-20T10:10:03.204294Z","end":"2026-03-20T10:10:03.325954Z","steps":["trace[1679634545] 'process raft request' (duration: 92.002395ms)","trace[1679634545] 'compare' (duration: 29.515335ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:13.374538Z","caller":"traceutil/trace.go:171","msg":"trace[777699177] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"104.392426ms","start":"2026-03-20T10:10:13.270125Z","end":"2026-03-20T10:10:13.374518Z","steps":["trace[777699177] 'read index received' (duration: 83.025746ms)","trace[777699177] 'applied index is now lower than readState.Index' (duration: 21.36541ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:13.374734Z","caller":"traceutil/trace.go:171","msg":"trace[806334918] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"132.616939ms","start":"2026-03-20T10:10:13.242085Z","end":"2026-03-20T10:10:13.374702Z","steps":["trace[806334918] 'process raft request' (duration: 111.185986ms)","trace[806334918] 'compare' (duration: 21.135192ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:10:13.375785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.175456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-20T10:10:13.37586Z","caller":"traceutil/trace.go:171","msg":"trace[1637411432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:813; }","duration":"105.76742ms","start":"2026-03-20T10:10:13.270077Z","end":"2026-03-20T10:10:13.375844Z","steps":["trace[1637411432] 'agreement among raft nodes before linearized reading' (duration: 104.621586ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:10:21.47139Z","caller":"traceutil/trace.go:171","msg":"trace[2097093457] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"100.881869ms","start":"2026-03-20T10:10:21.370478Z","end":"2026-03-20T10:10:21.47136Z","steps":["trace[2097093457] 'read index received' (duration: 43.092432ms)","trace[2097093457] 'applied index is now lower than readState.Index' (duration: 57.788236ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:21.471458Z","caller":"traceutil/trace.go:171","msg":"trace[1737956322] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"157.317812ms","start":"2026-03-20T10:10:21.314112Z","end":"2026-03-20T10:10:21.47143Z","steps":["trace[1737956322] 'process raft request' (duration: 99.517965ms)","trace[1737956322] 'compare' (duration: 57.588089ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:10:21.471626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.136478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:10:21.471686Z","caller":"traceutil/trace.go:171","msg":"trace[246279177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"101.265313ms","start":"2026-03-20T10:10:21.370403Z","end":"2026-03-20T10:10:21.471669Z","steps":["trace[246279177] 'agreement among raft nodes before linearized reading' (duration: 101.148159ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:10:21.658129Z","caller":"traceutil/trace.go:171","msg":"trace[57578828] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"182.117224ms","start":"2026-03-20T10:10:21.475982Z","end":"2026-03-20T10:10:21.6581Z","steps":["trace[57578828] 'process raft request' (duration: 129.260967ms)","trace[57578828] 'compare' (duration: 52.617128ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:21.658135Z","caller":"traceutil/trace.go:171","msg":"trace[81842325] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"181.835893ms","start":"2026-03-20T10:10:21.476268Z","end":"2026-03-20T10:10:21.658104Z","steps":["trace[81842325] 'process raft request' (duration: 181.723839ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:10:21.658214Z","caller":"traceutil/trace.go:171","msg":"trace[447960425] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"181.918826ms","start":"2026-03-20T10:10:21.476272Z","end":"2026-03-20T10:10:21.65819Z","steps":["trace[447960425] 'process raft request' (duration: 181.789021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:23.62307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.194633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:10:23.623187Z","caller":"traceutil/trace.go:171","msg":"trace[246841622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:839; }","duration":"147.337409ms","start":"2026-03-20T10:10:23.475822Z","end":"2026-03-20T10:10:23.623159Z","steps":["trace[246841622] 'range keys from in-memory index tree' (duration: 147.013306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:23.984573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.594078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:10:23.984681Z","caller":"traceutil/trace.go:171","msg":"trace[233789578] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"286.344009ms","start":"2026-03-20T10:10:23.698315Z","end":"2026-03-20T10:10:23.984659Z","steps":["trace[233789578] 'process raft request' (duration: 101.544013ms)","trace[233789578] 'compare' (duration: 184.343769ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:24.038698Z","caller":"traceutil/trace.go:171","msg":"trace[1899300020] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"340.032467ms","start":"2026-03-20T10:10:23.698649Z","end":"2026-03-20T10:10:24.038681Z","steps":["trace[1899300020] 'process raft request' (duration: 339.883001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:24.0388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:23.698631Z","time spent":"340.121162ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:10:24.038979Z","caller":"traceutil/trace.go:171","msg":"trace[89599906] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:876; }","duration":"126.548273ms","start":"2026-03-20T10:10:23.912388Z","end":"2026-03-20T10:10:24.038937Z","steps":["trace[89599906] 'read index received' (duration: 126.134856ms)","trace[89599906] 'applied index is now lower than readState.Index' (duration: 411.047µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:24.039004Z","caller":"traceutil/trace.go:171","msg":"trace[774327857] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"340.140622ms","start":"2026-03-20T10:10:23.698843Z","end":"2026-03-20T10:10:24.038984Z","steps":["trace[774327857] 'process raft request' (duration: 339.794998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:24.039189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:23.698835Z","time spent":"340.248146ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:10:24.039199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.801052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T10:10:24.039355Z","caller":"traceutil/trace.go:171","msg":"trace[1700616937] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:843; }","duration":"126.98653ms","start":"2026-03-20T10:10:23.912352Z","end":"2026-03-20T10:10:24.039338Z","steps":["trace[1700616937] 'agreement among raft nodes before linearized reading' (duration: 126.706859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:24.227174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.928484ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:10:24.227306Z","caller":"traceutil/trace.go:171","msg":"trace[403655348] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:843; }","duration":"105.084259ms","start":"2026-03-20T10:10:24.122202Z","end":"2026-03-20T10:10:24.227287Z","steps":["trace[403655348] 'range keys from in-memory index tree' (duration: 104.909283ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:10:33.714866Z","caller":"traceutil/trace.go:171","msg":"trace[149507794] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"211.181382ms","start":"2026-03-20T10:10:33.503664Z","end":"2026-03-20T10:10:33.714845Z","steps":["trace[149507794] 'process raft request' (duration: 211.055466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:34.117257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.328677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:10:34.117632Z","caller":"traceutil/trace.go:171","msg":"trace[699902305] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"157.072868ms","start":"2026-03-20T10:10:33.960537Z","end":"2026-03-20T10:10:34.117609Z","steps":["trace[699902305] 'process raft request' (duration: 52.300174ms)","trace[699902305] 'compare' (duration: 104.176031ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:34.515843Z","caller":"traceutil/trace.go:171","msg":"trace[786970480] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:907; }","duration":"394.216463ms","start":"2026-03-20T10:10:34.121601Z","end":"2026-03-20T10:10:34.515817Z","steps":["trace[786970480] 'read index received' (duration: 21.91842ms)","trace[786970480] 'applied index is now lower than readState.Index' (duration: 372.296703ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:34.515869Z","caller":"traceutil/trace.go:171","msg":"trace[1052346900] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"394.451012ms","start":"2026-03-20T10:10:34.12139Z","end":"2026-03-20T10:10:34.515841Z","steps":["trace[1052346900] 'process raft request' (duration: 330.068475ms)","trace[1052346900] 'compare' (duration: 64.160919ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:34.515937Z","caller":"traceutil/trace.go:171","msg":"trace[333848927] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"394.241873ms","start":"2026-03-20T10:10:34.121683Z","end":"2026-03-20T10:10:34.515924Z","steps":["trace[333848927] 'process raft request' (duration: 394.061326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:34.516057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:34.121659Z","time spent":"394.310486ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:10:34.516054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:34.121373Z","time spent":"394.590887ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:10:34.51607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.447713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T10:10:34.516132Z","caller":"traceutil/trace.go:171","msg":"trace[938420971] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:872; }","duration":"394.530385ms","start":"2026-03-20T10:10:34.121585Z","end":"2026-03-20T10:10:34.516115Z","steps":["trace[938420971] 'agreement among raft nodes before linearized reading' (duration: 394.353598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:34.516193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:34.121576Z","time spent":"394.607038ms","remote":"127.0.0.1:54002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-03-20T10:10:49.82279Z","caller":"traceutil/trace.go:171","msg":"trace[570996441] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"106.353142ms","start":"2026-03-20T10:10:49.716393Z","end":"2026-03-20T10:10:49.822746Z","steps":["trace[570996441] 'process raft request' (duration: 106.103192ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:10:50.234911Z","caller":"traceutil/trace.go:171","msg":"trace[69626176] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"391.402006ms","start":"2026-03-20T10:10:49.843483Z","end":"2026-03-20T10:10:50.234885Z","steps":["trace[69626176] 'read index received' (duration: 356.339613ms)","trace[69626176] 'applied index is now lower than readState.Index' (duration: 35.061143ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:50.234943Z","caller":"traceutil/trace.go:171","msg":"trace[1738387606] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"408.369775ms","start":"2026-03-20T10:10:49.826539Z","end":"2026-03-20T10:10:50.234909Z","steps":["trace[1738387606] 'process raft request' (duration: 373.212188ms)","trace[1738387606] 'compare' (duration: 34.988849ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:10:50.235074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.579452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T10:10:50.235101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:49.826518Z","time spent":"408.49616ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:10:50.235166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.85578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:10:50.235222Z","caller":"traceutil/trace.go:171","msg":"trace[1024879676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:913; }","duration":"317.948344ms","start":"2026-03-20T10:10:49.917256Z","end":"2026-03-20T10:10:50.235204Z","steps":["trace[1024879676] 'agreement among raft nodes before linearized reading' (duration: 317.785618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:50.235263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:49.917236Z","time spent":"318.016216ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-20T10:10:50.235113Z","caller":"traceutil/trace.go:171","msg":"trace[2033342192] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:913; }","duration":"391.683656ms","start":"2026-03-20T10:10:49.843416Z","end":"2026-03-20T10:10:50.2351Z","steps":["trace[2033342192] 'agreement among raft nodes before linearized reading' (duration: 391.604603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:50.235319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:49.843393Z","time spent":"391.912655ms","remote":"127.0.0.1:54452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-20T10:10:50.717852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:50.368057Z","time spent":"349.782252ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-20T10:10:50.71814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.45408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:10:50.718284Z","caller":"traceutil/trace.go:171","msg":"trace[176958756] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"345.636422ms","start":"2026-03-20T10:10:50.372611Z","end":"2026-03-20T10:10:50.718248Z","steps":["trace[176958756] 'read index received' (duration: 54.992015ms)","trace[176958756] 'applied index is now lower than readState.Index' (duration: 290.642997ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:50.718374Z","caller":"traceutil/trace.go:171","msg":"trace[1343082575] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"452.931291ms","start":"2026-03-20T10:10:50.265408Z","end":"2026-03-20T10:10:50.718339Z","steps":["trace[1343082575] 'process raft request' (duration: 162.152178ms)","trace[1343082575] 'compare' (duration: 290.17479ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:10:50.718453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.84003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:10:50.718502Z","caller":"traceutil/trace.go:171","msg":"trace[1198688656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"345.917303ms","start":"2026-03-20T10:10:50.37257Z","end":"2026-03-20T10:10:50.718487Z","steps":["trace[1198688656] 'agreement among raft nodes before linearized reading' (duration: 345.83851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:50.71852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:50.265385Z","time spent":"453.055296ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:10:50.718635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:50.372553Z","time spent":"345.988465ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T10:10:51.280419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.54091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:10:51.280517Z","caller":"traceutil/trace.go:171","msg":"trace[1964485275] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"552.18712ms","start":"2026-03-20T10:10:50.728314Z","end":"2026-03-20T10:10:51.280501Z","steps":["trace[1964485275] 'read index received' (duration: 376.504944ms)","trace[1964485275] 'applied index is now lower than readState.Index' (duration: 175.680716ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:10:51.280966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.645317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-20T10:10:51.281036Z","caller":"traceutil/trace.go:171","msg":"trace[959334496] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"552.745702ms","start":"2026-03-20T10:10:50.728262Z","end":"2026-03-20T10:10:51.281008Z","steps":["trace[959334496] 'agreement among raft nodes before linearized reading' (duration: 552.44333ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:10:51.281052Z","caller":"traceutil/trace.go:171","msg":"trace[1765723302] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"557.14095ms","start":"2026-03-20T10:10:50.723888Z","end":"2026-03-20T10:10:51.281029Z","steps":["trace[1765723302] 'process raft request' (duration: 380.894473ms)","trace[1765723302] 'compare' (duration: 175.384005ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:10:51.281192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:50.72386Z","time spent":"557.280446ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:10:51.281217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:10:50.728255Z","time spent":"552.819904ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-20T10:10:51.419247Z","caller":"traceutil/trace.go:171","msg":"trace[514004547] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"134.187497ms","start":"2026-03-20T10:10:51.285019Z","end":"2026-03-20T10:10:51.419206Z","steps":["trace[514004547] 'process raft request' (duration: 98.761052ms)","trace[514004547] 'compare' (duration: 35.065291ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:10:52.452665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.909213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:10:52.452799Z","caller":"traceutil/trace.go:171","msg":"trace[1218307954] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"184.39222ms","start":"2026-03-20T10:10:52.268379Z","end":"2026-03-20T10:10:52.452772Z","steps":["trace[1218307954] 'process raft request' (duration: 83.258648ms)","trace[1218307954] 'compare' (duration: 100.82679ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:58.682925Z","caller":"traceutil/trace.go:171","msg":"trace[354347828] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"210.240977ms","start":"2026-03-20T10:10:58.472662Z","end":"2026-03-20T10:10:58.682903Z","steps":["trace[354347828] 'read index received' (duration: 203.217169ms)","trace[354347828] 'applied index is now lower than readState.Index' (duration: 7.022698ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:10:58.683069Z","caller":"traceutil/trace.go:171","msg":"trace[1013195889] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"285.483087ms","start":"2026-03-20T10:10:58.397545Z","end":"2026-03-20T10:10:58.683028Z","steps":["trace[1013195889] 'process raft request' (duration: 278.284892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:58.683327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.648552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:10:58.683402Z","caller":"traceutil/trace.go:171","msg":"trace[1984594479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"210.757626ms","start":"2026-03-20T10:10:58.472627Z","end":"2026-03-20T10:10:58.683385Z","steps":["trace[1984594479] 'agreement among raft nodes before linearized reading' (duration: 210.525438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:10:58.683506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.361638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-734e165c-12e2-4be7-8230-7fa3d4868610\" ","response":"range_response_count:1 size:3887"} {"level":"info","ts":"2026-03-20T10:10:58.683728Z","caller":"traceutil/trace.go:171","msg":"trace[1511759752] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-734e165c-12e2-4be7-8230-7fa3d4868610; range_end:; response_count:1; response_revision:953; }","duration":"137.663399ms","start":"2026-03-20T10:10:58.546025Z","end":"2026-03-20T10:10:58.683688Z","steps":["trace[1511759752] 'agreement among raft nodes before linearized reading' (duration: 137.331586ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:00.539923Z","caller":"traceutil/trace.go:171","msg":"trace[60022700] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"148.94389ms","start":"2026-03-20T10:11:00.390953Z","end":"2026-03-20T10:11:00.539897Z","steps":["trace[60022700] 'process raft request' (duration: 148.789775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:04.143631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.789308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:11:04.143865Z","caller":"traceutil/trace.go:171","msg":"trace[1999769389] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"211.591868ms","start":"2026-03-20T10:11:03.932228Z","end":"2026-03-20T10:11:04.143819Z","steps":["trace[1999769389] 'process raft request' (duration: 93.54948ms)","trace[1999769389] 'compare' (duration: 117.665183ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:04.596869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.563488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:11:04.596949Z","caller":"traceutil/trace.go:171","msg":"trace[573975405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"216.693002ms","start":"2026-03-20T10:11:04.380237Z","end":"2026-03-20T10:11:04.59693Z","steps":["trace[573975405] 'range keys from in-memory index tree' (duration: 216.463194ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:04.77434Z","caller":"traceutil/trace.go:171","msg":"trace[709491947] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"145.627043ms","start":"2026-03-20T10:11:04.628695Z","end":"2026-03-20T10:11:04.774322Z","steps":["trace[709491947] 'process raft request' (duration: 145.484758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:04.968169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.337635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-20T10:11:04.968317Z","caller":"traceutil/trace.go:171","msg":"trace[1988788841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:984; }","duration":"155.536623ms","start":"2026-03-20T10:11:04.81276Z","end":"2026-03-20T10:11:04.968296Z","steps":["trace[1988788841] 'range keys from in-memory index tree' (duration: 155.208331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:04.968163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.186906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:11:04.968435Z","caller":"traceutil/trace.go:171","msg":"trace[1245773159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"169.510239ms","start":"2026-03-20T10:11:04.79891Z","end":"2026-03-20T10:11:04.968421Z","steps":["trace[1245773159] 'range keys from in-memory index tree' (duration: 168.991589ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:05.75343Z","caller":"traceutil/trace.go:171","msg":"trace[1930723607] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"102.466942ms","start":"2026-03-20T10:11:05.65093Z","end":"2026-03-20T10:11:05.753397Z","steps":["trace[1930723607] 'read index received' (duration: 102.226773ms)","trace[1930723607] 'applied index is now lower than readState.Index' (duration: 239.059µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:05.753493Z","caller":"traceutil/trace.go:171","msg":"trace[143734977] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"333.201593ms","start":"2026-03-20T10:11:05.420264Z","end":"2026-03-20T10:11:05.753465Z","steps":["trace[143734977] 'process raft request' (duration: 332.986874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:05.753655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.716122ms","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":"warn","ts":"2026-03-20T10:11:05.753648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:05.420248Z","time spent":"333.305816ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:11:05.753708Z","caller":"traceutil/trace.go:171","msg":"trace[1024160485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"102.813425ms","start":"2026-03-20T10:11:05.650879Z","end":"2026-03-20T10:11:05.753692Z","steps":["trace[1024160485] 'agreement among raft nodes before linearized reading' (duration: 102.640879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:06.46905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.223246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:11:06.469357Z","caller":"traceutil/trace.go:171","msg":"trace[1401178132] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"711.431466ms","start":"2026-03-20T10:11:05.757894Z","end":"2026-03-20T10:11:06.469326Z","steps":["trace[1401178132] 'process raft request' (duration: 408.835226ms)","trace[1401178132] 'compare' (duration: 301.866622ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:06.469493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:05.757871Z","time spent":"711.547281ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:11:06.540381Z","caller":"traceutil/trace.go:171","msg":"trace[70078880] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"105.198096ms","start":"2026-03-20T10:11:06.435157Z","end":"2026-03-20T10:11:06.540355Z","steps":["trace[70078880] 'process raft request' (duration: 105.02934ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:09.403381Z","caller":"traceutil/trace.go:171","msg":"trace[1768659916] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"344.885487ms","start":"2026-03-20T10:11:09.058472Z","end":"2026-03-20T10:11:09.403358Z","steps":["trace[1768659916] 'process raft request' (duration: 344.750192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:09.403557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:09.058453Z","time spent":"345.003552ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:11:10.348128Z","caller":"traceutil/trace.go:171","msg":"trace[1717551913] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"100.898031ms","start":"2026-03-20T10:11:10.247206Z","end":"2026-03-20T10:11:10.348104Z","steps":["trace[1717551913] 'process raft request' (duration: 100.766956ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:10.954238Z","caller":"traceutil/trace.go:171","msg":"trace[1079548312] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"114.142327ms","start":"2026-03-20T10:11:10.840069Z","end":"2026-03-20T10:11:10.954211Z","steps":["trace[1079548312] 'process raft request' (duration: 113.96203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:14.216004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.043941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:11:14.216243Z","caller":"traceutil/trace.go:171","msg":"trace[1135997295] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"253.944867ms","start":"2026-03-20T10:11:13.962261Z","end":"2026-03-20T10:11:14.216206Z","steps":["trace[1135997295] 'process raft request' (duration: 16.548243ms)","trace[1135997295] 'compare' (duration: 236.565423ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:14.21641Z","caller":"traceutil/trace.go:171","msg":"trace[1335905729] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"251.93752ms","start":"2026-03-20T10:11:13.964445Z","end":"2026-03-20T10:11:14.216382Z","steps":["trace[1335905729] 'process raft request' (duration: 251.693241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:15.236188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.915535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-20T10:11:15.236265Z","caller":"traceutil/trace.go:171","msg":"trace[1126623879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"128.041569ms","start":"2026-03-20T10:11:15.108206Z","end":"2026-03-20T10:11:15.236248Z","steps":["trace[1126623879] 'range keys from in-memory index tree' (duration: 127.756659ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:15.494392Z","caller":"traceutil/trace.go:171","msg":"trace[863782893] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"147.671829ms","start":"2026-03-20T10:11:15.346694Z","end":"2026-03-20T10:11:15.494366Z","steps":["trace[863782893] 'read index received' (duration: 147.545205ms)","trace[863782893] 'applied index is now lower than readState.Index' (duration: 125.314µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:15.494433Z","caller":"traceutil/trace.go:171","msg":"trace[783709644] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"253.292231ms","start":"2026-03-20T10:11:15.241116Z","end":"2026-03-20T10:11:15.494408Z","steps":["trace[783709644] 'process raft request' (duration: 253.083833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:15.494845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.616845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T10:11:15.494865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.084365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2569"} {"level":"info","ts":"2026-03-20T10:11:15.4949Z","caller":"traceutil/trace.go:171","msg":"trace[1596906641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"125.764971ms","start":"2026-03-20T10:11:15.36912Z","end":"2026-03-20T10:11:15.494885Z","steps":["trace[1596906641] 'agreement among raft nodes before linearized reading' (duration: 125.384897ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:15.49494Z","caller":"traceutil/trace.go:171","msg":"trace[598441926] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1017; }","duration":"148.298914ms","start":"2026-03-20T10:11:15.346622Z","end":"2026-03-20T10:11:15.494921Z","steps":["trace[598441926] 'agreement among raft nodes before linearized reading' (duration: 147.873658ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:15.694032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.371396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:11:15.694121Z","caller":"traceutil/trace.go:171","msg":"trace[674559143] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1064; }","duration":"193.561018ms","start":"2026-03-20T10:11:15.500542Z","end":"2026-03-20T10:11:15.694103Z","steps":["trace[674559143] 'read index received' (duration: 67.029795ms)","trace[674559143] 'applied index is now lower than readState.Index' (duration: 126.530293ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:15.69415Z","caller":"traceutil/trace.go:171","msg":"trace[2145562854] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"194.503823ms","start":"2026-03-20T10:11:15.499617Z","end":"2026-03-20T10:11:15.694121Z","steps":["trace[2145562854] 'process raft request' (duration: 67.95418ms)","trace[2145562854] 'compare' (duration: 126.262852ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:15.694241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.694793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2569"} {"level":"info","ts":"2026-03-20T10:11:15.694273Z","caller":"traceutil/trace.go:171","msg":"trace[194811534] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1018; }","duration":"193.749385ms","start":"2026-03-20T10:11:15.500514Z","end":"2026-03-20T10:11:15.694264Z","steps":["trace[194811534] 'agreement among raft nodes before linearized reading' (duration: 193.633601ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:16.721051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959272724011269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T10:11:17.043642Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.290566921s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T10:11:17.0442Z","caller":"traceutil/trace.go:171","msg":"trace[970407154] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.291216715s","start":"2026-03-20T10:11:15.752959Z","end":"2026-03-20T10:11:17.044175Z","steps":["trace[970407154] 'process raft request' (duration: 1.291050629s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:17.044502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:15.7528Z","time spent":"1.291458465s","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T10:11:17.741841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.607957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:11:17.742048Z","caller":"traceutil/trace.go:171","msg":"trace[1867494222] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1066; }","duration":"1.521246997s","start":"2026-03-20T10:11:16.220778Z","end":"2026-03-20T10:11:17.742025Z","steps":["trace[1867494222] 'read index received' (duration: 823.039901ms)","trace[1867494222] 'applied index is now lower than readState.Index' (duration: 698.204706ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:17.742233Z","caller":"traceutil/trace.go:171","msg":"trace[36643918] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.183808467s","start":"2026-03-20T10:11:16.558402Z","end":"2026-03-20T10:11:17.74221Z","steps":["trace[36643918] 'process raft request' (duration: 891.742181ms)","trace[36643918] 'compare' (duration: 291.475792ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:17.742407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:16.558375Z","time spent":"1.183969703s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:11:17.742488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053274761s","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":"warn","ts":"2026-03-20T10:11:17.742418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.495053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:11:17.742552Z","caller":"traceutil/trace.go:171","msg":"trace[1708664830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.053387195s","start":"2026-03-20T10:11:16.689149Z","end":"2026-03-20T10:11:17.742536Z","steps":["trace[1708664830] 'agreement among raft nodes before linearized reading' (duration: 1.053190608s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:17.742518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521751046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-20T10:11:17.742621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:16.689127Z","time spent":"1.053481619s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T10:11:17.742746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.200904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-20T10:11:17.742571Z","caller":"traceutil/trace.go:171","msg":"trace[1017507023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"366.683771ms","start":"2026-03-20T10:11:17.375869Z","end":"2026-03-20T10:11:17.742553Z","steps":["trace[1017507023] 'agreement among raft nodes before linearized reading' (duration: 366.484803ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:17.742784Z","caller":"traceutil/trace.go:171","msg":"trace[2141867490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"244.270576ms","start":"2026-03-20T10:11:17.498505Z","end":"2026-03-20T10:11:17.742775Z","steps":["trace[2141867490] 'agreement among raft nodes before linearized reading' (duration: 244.172752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:17.742795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:17.375853Z","time spent":"366.930349ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T10:11:17.742822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.581634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:11:17.742632Z","caller":"traceutil/trace.go:171","msg":"trace[1704441639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.521902233s","start":"2026-03-20T10:11:16.220714Z","end":"2026-03-20T10:11:17.742617Z","steps":["trace[1704441639] 'agreement among raft nodes before linearized reading' (duration: 1.521614972s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:17.742879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:16.220693Z","time spent":"1.522178304s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T10:11:17.742882Z","caller":"traceutil/trace.go:171","msg":"trace[402559307] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1021; }","duration":"753.672067ms","start":"2026-03-20T10:11:16.989194Z","end":"2026-03-20T10:11:17.742866Z","steps":["trace[402559307] 'agreement among raft nodes before linearized reading' (duration: 753.556503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:17.742921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:16.989179Z","time spent":"753.735129ms","remote":"127.0.0.1:54106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-03-20T10:11:17.743146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.328395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-20T10:11:17.743275Z","caller":"traceutil/trace.go:171","msg":"trace[1132742074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"706.491662ms","start":"2026-03-20T10:11:17.036747Z","end":"2026-03-20T10:11:17.743238Z","steps":["trace[1132742074] 'agreement among raft nodes before linearized reading' (duration: 706.042595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:17.744289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:17.036736Z","time spent":"706.643608ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T10:11:19.09526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040210412s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:11:19.095741Z","caller":"traceutil/trace.go:171","msg":"trace[2120703951] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.347868082s","start":"2026-03-20T10:11:17.747858Z","end":"2026-03-20T10:11:19.095726Z","steps":["trace[2120703951] 'process raft request' (duration: 307.126001ms)","trace[2120703951] 'compare' (duration: 1.039925389s)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:19.095855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346716889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:11:19.095895Z","caller":"traceutil/trace.go:171","msg":"trace[921891155] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1022; }","duration":"1.346784091s","start":"2026-03-20T10:11:17.749101Z","end":"2026-03-20T10:11:19.095885Z","steps":["trace[921891155] 'agreement among raft nodes before linearized reading' (duration: 1.346720259s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:19.09586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:17.747834Z","time spent":"1.347989387s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:11:19.095924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:17.749061Z","time spent":"1.346855004s","remote":"127.0.0.1:54106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-03-20T10:11:19.096337Z","caller":"traceutil/trace.go:171","msg":"trace[491265796] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"1.346534742s","start":"2026-03-20T10:11:17.749132Z","end":"2026-03-20T10:11:19.095667Z","steps":["trace[491265796] 'read index received' (duration: 305.719858ms)","trace[491265796] 'applied index is now lower than readState.Index' (duration: 1.040813604s)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:19.893201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959272724011280,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T10:11:20.121368Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.066521118s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-20T10:11:20.246165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.211262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:11:20.246346Z","caller":"traceutil/trace.go:171","msg":"trace[966064324] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"2.497175836s","start":"2026-03-20T10:11:17.749143Z","end":"2026-03-20T10:11:20.246319Z","steps":["trace[966064324] 'process raft request' (duration: 2.372738095s)","trace[966064324] 'compare' (duration: 124.047496ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:20.246485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:17.74912Z","time spent":"2.49729505s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:11:20.246862Z","caller":"traceutil/trace.go:171","msg":"trace[821149577] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"2.497581441s","start":"2026-03-20T10:11:17.749252Z","end":"2026-03-20T10:11:20.246834Z","steps":["trace[821149577] 'process raft request' (duration: 2.49705763s)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:20.24691Z","caller":"traceutil/trace.go:171","msg":"trace[1264792544] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"2.497570432s","start":"2026-03-20T10:11:17.749325Z","end":"2026-03-20T10:11:20.246895Z","steps":["trace[1264792544] 'process raft request' (duration: 2.497007849s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:20.246985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:17.74931Z","time spent":"2.497640484s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:11:20.246996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:17.749244Z","time spent":"2.497679556s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T10:11:20.247318Z","caller":"traceutil/trace.go:171","msg":"trace[123792350] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"2.498137613s","start":"2026-03-20T10:11:17.749168Z","end":"2026-03-20T10:11:20.247306Z","steps":["trace[123792350] 'process raft request' (duration: 2.497082762s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:20.247391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:17.749152Z","time spent":"2.498204155s","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:11:20.386464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.08105ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-20T10:11:20.38657Z","caller":"traceutil/trace.go:171","msg":"trace[1102442482] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1069; }","duration":"994.239011ms","start":"2026-03-20T10:11:19.392312Z","end":"2026-03-20T10:11:20.386551Z","steps":["trace[1102442482] 'read index received' (duration: 729.533603ms)","trace[1102442482] 'applied index is now lower than readState.Index' (duration: 264.703868ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:20.386678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.360595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:11:20.386713Z","caller":"traceutil/trace.go:171","msg":"trace[564192954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"994.434158ms","start":"2026-03-20T10:11:19.392265Z","end":"2026-03-20T10:11:20.3867Z","steps":["trace[564192954] 'agreement among raft nodes before linearized reading' (duration: 994.364085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:20.386745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:19.392246Z","time spent":"994.49192ms","remote":"127.0.0.1:53974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T10:11:20.413187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.357643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T10:11:20.413277Z","caller":"traceutil/trace.go:171","msg":"trace[1337454357] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1027; }","duration":"805.502608ms","start":"2026-03-20T10:11:19.607757Z","end":"2026-03-20T10:11:20.413259Z","steps":["trace[1337454357] 'agreement among raft nodes before linearized reading' (duration: 805.263029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:20.413318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:19.607734Z","time spent":"805.572621ms","remote":"127.0.0.1:54462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-03-20T10:11:21.245309Z","caller":"traceutil/trace.go:171","msg":"trace[494238904] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"139.957175ms","start":"2026-03-20T10:11:21.105318Z","end":"2026-03-20T10:11:21.245275Z","steps":["trace[494238904] 'process raft request' (duration: 139.720276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:21.608357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.065133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T10:11:21.608436Z","caller":"traceutil/trace.go:171","msg":"trace[212674240] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1028; }","duration":"207.210798ms","start":"2026-03-20T10:11:21.401205Z","end":"2026-03-20T10:11:21.608415Z","steps":["trace[212674240] 'count revisions from in-memory index tree' (duration: 206.972629ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:22.37252Z","caller":"traceutil/trace.go:171","msg":"trace[1703854072] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"119.206651ms","start":"2026-03-20T10:11:22.253286Z","end":"2026-03-20T10:11:22.372493Z","steps":["trace[1703854072] 'read index received' (duration: 119.135228ms)","trace[1703854072] 'applied index is now lower than readState.Index' (duration: 69.973µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:22.372691Z","caller":"traceutil/trace.go:171","msg":"trace[1227956022] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"119.361487ms","start":"2026-03-20T10:11:22.25326Z","end":"2026-03-20T10:11:22.372622Z","steps":["trace[1227956022] 'process raft request' (duration: 119.084576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:22.373024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.714801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-20T10:11:22.373261Z","caller":"traceutil/trace.go:171","msg":"trace[2107123094] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1029; }","duration":"119.983261ms","start":"2026-03-20T10:11:22.253257Z","end":"2026-03-20T10:11:22.373241Z","steps":["trace[2107123094] 'agreement among raft nodes before linearized reading' (duration: 119.370857ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:22.549307Z","caller":"traceutil/trace.go:171","msg":"trace[1885940041] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"294.628663ms","start":"2026-03-20T10:11:22.254649Z","end":"2026-03-20T10:11:22.549278Z","steps":["trace[1885940041] 'process raft request' (duration: 294.258598ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:22.549511Z","caller":"traceutil/trace.go:171","msg":"trace[869050655] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"294.751678ms","start":"2026-03-20T10:11:22.254734Z","end":"2026-03-20T10:11:22.549486Z","steps":["trace[869050655] 'process raft request' (duration: 294.483768ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:22.549655Z","caller":"traceutil/trace.go:171","msg":"trace[1628760887] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1077; }","duration":"177.033064ms","start":"2026-03-20T10:11:22.3726Z","end":"2026-03-20T10:11:22.549633Z","steps":["trace[1628760887] 'read index received' (duration: 176.198921ms)","trace[1628760887] 'applied index is now lower than readState.Index' (duration: 831.383µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:22.549799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.089621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:11:22.549859Z","caller":"traceutil/trace.go:171","msg":"trace[1833002788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"180.199105ms","start":"2026-03-20T10:11:22.369645Z","end":"2026-03-20T10:11:22.549844Z","steps":["trace[1833002788] 'agreement among raft nodes before linearized reading' (duration: 180.096621ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:22.557349Z","caller":"traceutil/trace.go:171","msg":"trace[1051275748] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"178.476967ms","start":"2026-03-20T10:11:22.378862Z","end":"2026-03-20T10:11:22.557339Z","steps":["trace[1051275748] 'process raft request' (duration: 178.348562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:22.936673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.47631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2581"} {"level":"info","ts":"2026-03-20T10:11:22.936753Z","caller":"traceutil/trace.go:171","msg":"trace[1791295462] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1033; }","duration":"154.597645ms","start":"2026-03-20T10:11:22.782137Z","end":"2026-03-20T10:11:22.936735Z","steps":["trace[1791295462] 'range keys from in-memory index tree' (duration: 154.241821ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:23.36433Z","caller":"traceutil/trace.go:171","msg":"trace[586706105] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"112.720553ms","start":"2026-03-20T10:11:23.251584Z","end":"2026-03-20T10:11:23.364305Z","steps":["trace[586706105] 'process raft request' (duration: 112.585678ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:23.739014Z","caller":"traceutil/trace.go:171","msg":"trace[358322112] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"245.108197ms","start":"2026-03-20T10:11:23.493881Z","end":"2026-03-20T10:11:23.738989Z","steps":["trace[358322112] 'process raft request' (duration: 244.928551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:23.943717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.588485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:2780"} {"level":"info","ts":"2026-03-20T10:11:23.943835Z","caller":"traceutil/trace.go:171","msg":"trace[1548553545] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"132.230899ms","start":"2026-03-20T10:11:23.811578Z","end":"2026-03-20T10:11:23.943809Z","steps":["trace[1548553545] 'read index received' (duration: 34.522µs)","trace[1548553545] 'applied index is now lower than readState.Index' (duration: 132.194947ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:23.943952Z","caller":"traceutil/trace.go:171","msg":"trace[636943923] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1036; }","duration":"203.119111ms","start":"2026-03-20T10:11:23.740777Z","end":"2026-03-20T10:11:23.943896Z","steps":["trace[636943923] 'process raft request' (duration: 43.191102ms)","trace[636943923] 'compare' (duration: 159.518603ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:23.944021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.417496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1780"} {"level":"info","ts":"2026-03-20T10:11:23.944097Z","caller":"traceutil/trace.go:171","msg":"trace[976740733] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:1036; }","duration":"132.536921ms","start":"2026-03-20T10:11:23.811546Z","end":"2026-03-20T10:11:23.944082Z","steps":["trace[976740733] 'agreement among raft nodes before linearized reading' (duration: 132.348333ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:26.627186Z","caller":"traceutil/trace.go:171","msg":"trace[2104353745] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"193.224072ms","start":"2026-03-20T10:11:26.433937Z","end":"2026-03-20T10:11:26.627161Z","steps":["trace[2104353745] 'read index received' (duration: 193.112828ms)","trace[2104353745] 'applied index is now lower than readState.Index' (duration: 110.104µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:26.627343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.383488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-03-20T10:11:26.627316Z","caller":"traceutil/trace.go:171","msg":"trace[1522811416] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"209.604329ms","start":"2026-03-20T10:11:26.417673Z","end":"2026-03-20T10:11:26.627277Z","steps":["trace[1522811416] 'process raft request' (duration: 209.35336ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:26.627386Z","caller":"traceutil/trace.go:171","msg":"trace[871633448] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:1044; }","duration":"193.460341ms","start":"2026-03-20T10:11:26.433915Z","end":"2026-03-20T10:11:26.627375Z","steps":["trace[871633448] 'agreement among raft nodes before linearized reading' (duration: 193.371398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:26.627592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.745232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-03-20T10:11:26.627791Z","caller":"traceutil/trace.go:171","msg":"trace[1321362423] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:1044; }","duration":"147.873427ms","start":"2026-03-20T10:11:26.479792Z","end":"2026-03-20T10:11:26.627665Z","steps":["trace[1321362423] 'agreement among raft nodes before linearized reading' (duration: 147.69997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:26.851199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.183244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T10:11:26.851424Z","caller":"traceutil/trace.go:171","msg":"trace[1560215452] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1095; }","duration":"206.215868ms","start":"2026-03-20T10:11:26.645185Z","end":"2026-03-20T10:11:26.851401Z","steps":["trace[1560215452] 'read index received' (duration: 72.618908ms)","trace[1560215452] 'applied index is now lower than readState.Index' (duration: 133.59556ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:26.851549Z","caller":"traceutil/trace.go:171","msg":"trace[1008032030] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"218.165926ms","start":"2026-03-20T10:11:26.633368Z","end":"2026-03-20T10:11:26.851534Z","steps":["trace[1008032030] 'process raft request' (duration: 217.952248ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:26.851628Z","caller":"traceutil/trace.go:171","msg":"trace[1379371116] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"218.467218ms","start":"2026-03-20T10:11:26.633123Z","end":"2026-03-20T10:11:26.851591Z","steps":["trace[1379371116] 'process raft request' (duration: 84.783524ms)","trace[1379371116] 'compare' (duration: 133.001498ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:26.851664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.581496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-03-20T10:11:26.851747Z","caller":"traceutil/trace.go:171","msg":"trace[1439880518] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:1047; }","duration":"118.714432ms","start":"2026-03-20T10:11:26.733012Z","end":"2026-03-20T10:11:26.851726Z","steps":["trace[1439880518] 'agreement among raft nodes before linearized reading' (duration: 118.571946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:26.851925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.909964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:1 size:2379"} {"level":"info","ts":"2026-03-20T10:11:26.851966Z","caller":"traceutil/trace.go:171","msg":"trace[736839968] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:1; response_revision:1047; }","duration":"218.981737ms","start":"2026-03-20T10:11:26.632973Z","end":"2026-03-20T10:11:26.851954Z","steps":["trace[736839968] 'agreement among raft nodes before linearized reading' (duration: 218.873303ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:28.243111Z","caller":"traceutil/trace.go:171","msg":"trace[19037142] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"344.552912ms","start":"2026-03-20T10:11:27.898535Z","end":"2026-03-20T10:11:28.243088Z","steps":["trace[19037142] 'read index received' (duration: 344.338424ms)","trace[19037142] 'applied index is now lower than readState.Index' (duration: 213.628µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:28.243199Z","caller":"traceutil/trace.go:171","msg":"trace[2139296149] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"366.037904ms","start":"2026-03-20T10:11:27.877129Z","end":"2026-03-20T10:11:28.243167Z","steps":["trace[2139296149] 'process raft request' (duration: 365.798865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:28.243322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.027109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:720"} {"level":"warn","ts":"2026-03-20T10:11:28.243334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:27.877103Z","time spent":"366.161488ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T10:11:28.243325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.751179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-03-20T10:11:28.243367Z","caller":"traceutil/trace.go:171","msg":"trace[2110247268] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:1049; }","duration":"328.129734ms","start":"2026-03-20T10:11:27.915223Z","end":"2026-03-20T10:11:28.243353Z","steps":["trace[2110247268] 'agreement among raft nodes before linearized reading' (duration: 328.03131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:28.243432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:27.915203Z","time spent":"328.220457ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":743,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" "} {"level":"info","ts":"2026-03-20T10:11:28.243423Z","caller":"traceutil/trace.go:171","msg":"trace[2017797891] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:1049; }","duration":"344.888614ms","start":"2026-03-20T10:11:27.898506Z","end":"2026-03-20T10:11:28.243395Z","steps":["trace[2017797891] 'agreement among raft nodes before linearized reading' (duration: 344.679986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:28.244954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T10:11:27.898494Z","time spent":"346.406272ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":725,"request content":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" "} {"level":"warn","ts":"2026-03-20T10:11:28.495947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.524569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:11:28.496037Z","caller":"traceutil/trace.go:171","msg":"trace[1425746565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"127.633213ms","start":"2026-03-20T10:11:28.368386Z","end":"2026-03-20T10:11:28.496019Z","steps":["trace[1425746565] 'range keys from in-memory index tree' (duration: 127.456576ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:28.961628Z","caller":"traceutil/trace.go:171","msg":"trace[1356481198] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"102.599166ms","start":"2026-03-20T10:11:28.859004Z","end":"2026-03-20T10:11:28.961603Z","steps":["trace[1356481198] 'process raft request' (duration: 102.401597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:29.625594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.12853ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-20T10:11:29.625755Z","caller":"traceutil/trace.go:171","msg":"trace[1715290993] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"207.054252ms","start":"2026-03-20T10:11:29.418683Z","end":"2026-03-20T10:11:29.625737Z","steps":["trace[1715290993] 'read index received' (duration: 31.764645ms)","trace[1715290993] 'applied index is now lower than readState.Index' (duration: 175.288067ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:29.626289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.379104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-20T10:11:29.626375Z","caller":"traceutil/trace.go:171","msg":"trace[2101529635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1053; }","duration":"207.713236ms","start":"2026-03-20T10:11:29.418645Z","end":"2026-03-20T10:11:29.626358Z","steps":["trace[2101529635] 'agreement among raft nodes before linearized reading' (duration: 207.269999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:29.633382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.944087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:793"} {"level":"info","ts":"2026-03-20T10:11:29.633393Z","caller":"traceutil/trace.go:171","msg":"trace[1497915652] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"173.589771ms","start":"2026-03-20T10:11:29.459768Z","end":"2026-03-20T10:11:29.633357Z","steps":["trace[1497915652] 'process raft request' (duration: 173.411764ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:29.63345Z","caller":"traceutil/trace.go:171","msg":"trace[166493802] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1054; }","duration":"212.025481ms","start":"2026-03-20T10:11:29.421408Z","end":"2026-03-20T10:11:29.633433Z","steps":["trace[166493802] 'agreement among raft nodes before linearized reading' (duration: 211.905636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:29.633471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.027566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:843"} {"level":"info","ts":"2026-03-20T10:11:29.633531Z","caller":"traceutil/trace.go:171","msg":"trace[1377148082] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1054; }","duration":"209.13275ms","start":"2026-03-20T10:11:29.424382Z","end":"2026-03-20T10:11:29.633515Z","steps":["trace[1377148082] 'agreement among raft nodes before linearized reading' (duration: 209.005775ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:40.809398Z","caller":"traceutil/trace.go:171","msg":"trace[1846649420] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"102.71867ms","start":"2026-03-20T10:11:40.706659Z","end":"2026-03-20T10:11:40.809378Z","steps":["trace[1846649420] 'read index received' (duration: 102.501071ms)","trace[1846649420] 'applied index is now lower than readState.Index' (duration: 216.939µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:40.809472Z","caller":"traceutil/trace.go:171","msg":"trace[2018848345] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"104.04336ms","start":"2026-03-20T10:11:40.705386Z","end":"2026-03-20T10:11:40.809429Z","steps":["trace[2018848345] 'process raft request' (duration: 103.840392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:40.809579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.892536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-03-20T10:11:40.809683Z","caller":"traceutil/trace.go:171","msg":"trace[465033017] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1084; }","duration":"103.028251ms","start":"2026-03-20T10:11:40.706635Z","end":"2026-03-20T10:11:40.809663Z","steps":["trace[465033017] 'agreement among raft nodes before linearized reading' (duration: 102.826924ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:40.923585Z","caller":"traceutil/trace.go:171","msg":"trace[64081010] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"109.315622ms","start":"2026-03-20T10:11:40.814245Z","end":"2026-03-20T10:11:40.923561Z","steps":["trace[64081010] 'read index received' (duration: 68.454019ms)","trace[64081010] 'applied index is now lower than readState.Index' (duration: 40.860573ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:40.923775Z","caller":"traceutil/trace.go:171","msg":"trace[1103586018] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"110.175975ms","start":"2026-03-20T10:11:40.813554Z","end":"2026-03-20T10:11:40.92373Z","steps":["trace[1103586018] 'process raft request' (duration: 69.116454ms)","trace[1103586018] 'compare' (duration: 40.77434ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:40.923889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.623634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-03-20T10:11:40.92395Z","caller":"traceutil/trace.go:171","msg":"trace[17665465] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1085; }","duration":"109.732848ms","start":"2026-03-20T10:11:40.814205Z","end":"2026-03-20T10:11:40.923938Z","steps":["trace[17665465] 'agreement among raft nodes before linearized reading' (duration: 109.622414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:40.932857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.492254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-03-20T10:11:40.932928Z","caller":"traceutil/trace.go:171","msg":"trace[256092415] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1086; }","duration":"118.599667ms","start":"2026-03-20T10:11:40.814311Z","end":"2026-03-20T10:11:40.932911Z","steps":["trace[256092415] 'agreement among raft nodes before linearized reading' (duration: 118.491793ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:41.125029Z","caller":"traceutil/trace.go:171","msg":"trace[1034119430] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"107.272083ms","start":"2026-03-20T10:11:41.017734Z","end":"2026-03-20T10:11:41.125006Z","steps":["trace[1034119430] 'process raft request' (duration: 77.37193ms)","trace[1034119430] 'compare' (duration: 29.780359ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:45.936934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.133038ms","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-03-20T10:11:45.937027Z","caller":"traceutil/trace.go:171","msg":"trace[1117399464] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:1100; }","duration":"113.275733ms","start":"2026-03-20T10:11:45.823731Z","end":"2026-03-20T10:11:45.937007Z","steps":["trace[1117399464] 'range keys from in-memory index tree' (duration: 113.033254ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:47.264161Z","caller":"traceutil/trace.go:171","msg":"trace[1388611977] linearizableReadLoop","detail":"{readStateIndex:1157; appliedIndex:1156; }","duration":"150.635303ms","start":"2026-03-20T10:11:47.113502Z","end":"2026-03-20T10:11:47.264137Z","steps":["trace[1388611977] 'read index received' (duration: 104.656863ms)","trace[1388611977] 'applied index is now lower than readState.Index' (duration: 45.97726ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:47.264193Z","caller":"traceutil/trace.go:171","msg":"trace[859085543] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"227.563735ms","start":"2026-03-20T10:11:47.036593Z","end":"2026-03-20T10:11:47.264157Z","steps":["trace[859085543] 'process raft request' (duration: 181.588875ms)","trace[859085543] 'compare' (duration: 45.625976ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T10:11:47.264337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.81305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-20T10:11:47.264377Z","caller":"traceutil/trace.go:171","msg":"trace[1531986453] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1103; }","duration":"150.892923ms","start":"2026-03-20T10:11:47.113474Z","end":"2026-03-20T10:11:47.264366Z","steps":["trace[1531986453] 'agreement among raft nodes before linearized reading' (duration: 150.799509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:47.264505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.358772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-20T10:11:47.264612Z","caller":"traceutil/trace.go:171","msg":"trace[136937246] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:1103; }","duration":"121.511188ms","start":"2026-03-20T10:11:47.143078Z","end":"2026-03-20T10:11:47.264589Z","steps":["trace[136937246] 'agreement among raft nodes before linearized reading' (duration: 121.275269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:47.264756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.249027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-20T10:11:47.264799Z","caller":"traceutil/trace.go:171","msg":"trace[532959505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"110.332521ms","start":"2026-03-20T10:11:47.154457Z","end":"2026-03-20T10:11:47.264789Z","steps":["trace[532959505] 'agreement among raft nodes before linearized reading' (duration: 110.209336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:51.108583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.034078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T10:11:51.108693Z","caller":"traceutil/trace.go:171","msg":"trace[1656363374] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1112; }","duration":"111.189714ms","start":"2026-03-20T10:11:50.997474Z","end":"2026-03-20T10:11:51.108664Z","steps":["trace[1656363374] 'count revisions from in-memory index tree' (duration: 110.964924ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:51.108883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.273785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T10:11:51.109006Z","caller":"traceutil/trace.go:171","msg":"trace[1338847493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1112; }","duration":"123.463283ms","start":"2026-03-20T10:11:50.985515Z","end":"2026-03-20T10:11:51.108978Z","steps":["trace[1338847493] 'range keys from in-memory index tree' (duration: 123.028857ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T10:11:51.413421Z","caller":"traceutil/trace.go:171","msg":"trace[217595957] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"127.420145ms","start":"2026-03-20T10:11:51.285981Z","end":"2026-03-20T10:11:51.413401Z","steps":["trace[217595957] 'read index received' (duration: 127.380623ms)","trace[217595957] 'applied index is now lower than readState.Index' (duration: 38.602µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T10:11:51.413488Z","caller":"traceutil/trace.go:171","msg":"trace[200683874] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"131.193359ms","start":"2026-03-20T10:11:51.282263Z","end":"2026-03-20T10:11:51.413456Z","steps":["trace[200683874] 'process raft request' (duration: 131.014382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T10:11:51.41367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.672234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-20T10:11:51.413728Z","caller":"traceutil/trace.go:171","msg":"trace[756544700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1115; }","duration":"127.768778ms","start":"2026-03-20T10:11:51.285945Z","end":"2026-03-20T10:11:51.413713Z","steps":["trace[756544700] 'agreement among raft nodes before linearized reading' (duration: 127.602501ms)"],"step_count":1}