{"level":"warn","ts":"2026-05-31T05:50:44.564969Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-31T05:50:44.565053Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-05-31T05:50:44.565182Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-31T05:50:44.565195Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-31T05:50:44.565214Z","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-05-31T05:50:44.565837Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-05-31T05:50:44.565909Z","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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-05-31T05:50:44.571782Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.687372ms"} {"level":"info","ts":"2026-05-31T05:50:44.580252Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-05-31T05:50:44.580346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-05-31T05:50:44.580383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-05-31T05:50:44.580418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-31T05:50:44.580441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-05-31T05:50:44.580499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-05-31T05:50:44.593813Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-31T05:50:44.596736Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-31T05:50:44.597936Z","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-05-31T05:50:44.600441Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-31T05:50:44.601247Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-31T05:50:44.601482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-05-31T05:50:44.601559Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-31T05:50:44.601623Z","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-05-31T05:50:44.601652Z","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-05-31T05:50:44.601661Z","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-05-31T05:50:44.605342Z","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-05-31T05:50:44.605463Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-31T05:50:44.605477Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-31T05:50:44.605755Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-31T05:50:44.605797Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-31T05:50:44.781045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-05-31T05:50:44.781158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-31T05:50:44.781188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-05-31T05:50:44.781214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-05-31T05:50:44.781229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-31T05:50:44.781248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-05-31T05:50:44.781275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-31T05:50:44.783005Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-05-31T05:50:44.783247Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:50:44.783978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-31T05:50:44.784113Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-31T05:50:44.784175Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-31T05:50:44.784107Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-31T05:50:44.784406Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:50:44.784516Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:50:44.78455Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-31T05:50:44.785723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-05-31T05:50:44.787397Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-31T05:51:10.516339Z","caller":"traceutil/trace.go:171","msg":"trace[930312547] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"411.714873ms","start":"2026-05-31T05:51:10.104601Z","end":"2026-05-31T05:51:10.516316Z","steps":["trace[930312547] 'process raft request' (duration: 411.58209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:10.516903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:10.104575Z","time spent":"411.841046ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:10.535959Z","caller":"traceutil/trace.go:171","msg":"trace[285916076] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:444; }","duration":"263.760892ms","start":"2026-05-31T05:51:10.272177Z","end":"2026-05-31T05:51:10.535938Z","steps":["trace[285916076] 'read index received' (duration: 244.000285ms)","trace[285916076] 'applied index is now lower than readState.Index' (duration: 19.759387ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:10.536132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.933965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:10.536263Z","caller":"traceutil/trace.go:171","msg":"trace[1659363279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"264.11094ms","start":"2026-05-31T05:51:10.27213Z","end":"2026-05-31T05:51:10.536241Z","steps":["trace[1659363279] 'agreement among raft nodes before linearized reading' (duration: 263.893485ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:10.536016Z","caller":"traceutil/trace.go:171","msg":"trace[1750327051] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"337.458085ms","start":"2026-05-31T05:51:10.198515Z","end":"2026-05-31T05:51:10.535973Z","steps":["trace[1750327051] 'process raft request' (duration: 333.658542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:10.536539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:10.198489Z","time spent":"337.919526ms","remote":"127.0.0.1:34700","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-05-31T05:51:10.73737Z","caller":"traceutil/trace.go:171","msg":"trace[1645037881] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"151.501508ms","start":"2026-05-31T05:51:10.58582Z","end":"2026-05-31T05:51:10.737321Z","steps":["trace[1645037881] 'process raft request' (duration: 85.454633ms)","trace[1645037881] 'compare' (duration: 65.785159ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:17.032283Z","caller":"traceutil/trace.go:171","msg":"trace[82957373] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"453.477811ms","start":"2026-05-31T05:51:16.578779Z","end":"2026-05-31T05:51:17.032257Z","steps":["trace[82957373] 'read index received' (duration: 453.319327ms)","trace[82957373] 'applied index is now lower than readState.Index' (duration: 156.764µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:17.032415Z","caller":"traceutil/trace.go:171","msg":"trace[270426415] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"489.414875ms","start":"2026-05-31T05:51:16.542951Z","end":"2026-05-31T05:51:17.032366Z","steps":["trace[270426415] 'process raft request' (duration: 489.138968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:17.032533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.771894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:51:17.032568Z","caller":"traceutil/trace.go:171","msg":"trace[1010978985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:442; }","duration":"192.860456ms","start":"2026-05-31T05:51:16.839693Z","end":"2026-05-31T05:51:17.032554Z","steps":["trace[1010978985] 'agreement among raft nodes before linearized reading' (duration: 192.813975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:17.032553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.739657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-31T05:51:17.032614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:16.542942Z","time spent":"489.587469ms","remote":"127.0.0.1:34700","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-05-31T05:51:17.032623Z","caller":"traceutil/trace.go:171","msg":"trace[310152219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:442; }","duration":"453.857741ms","start":"2026-05-31T05:51:16.578747Z","end":"2026-05-31T05:51:17.032605Z","steps":["trace[310152219] 'agreement among raft nodes before linearized reading' (duration: 453.665816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:17.032481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.331776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-31T05:51:17.032676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:16.578736Z","time spent":"453.929772ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-31T05:51:17.032719Z","caller":"traceutil/trace.go:171","msg":"trace[133126971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:442; }","duration":"264.603062ms","start":"2026-05-31T05:51:16.768075Z","end":"2026-05-31T05:51:17.032678Z","steps":["trace[133126971] 'agreement among raft nodes before linearized reading' (duration: 264.339666ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:17.182703Z","caller":"traceutil/trace.go:171","msg":"trace[509575776] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"145.699756ms","start":"2026-05-31T05:51:17.036974Z","end":"2026-05-31T05:51:17.182673Z","steps":["trace[509575776] 'process raft request' (duration: 145.398578ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:17.182948Z","caller":"traceutil/trace.go:171","msg":"trace[2135129591] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"145.489241ms","start":"2026-05-31T05:51:17.037415Z","end":"2026-05-31T05:51:17.182905Z","steps":["trace[2135129591] 'process raft request' (duration: 145.196274ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:19.334205Z","caller":"traceutil/trace.go:171","msg":"trace[1180627629] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"244.815205ms","start":"2026-05-31T05:51:19.089367Z","end":"2026-05-31T05:51:19.334182Z","steps":["trace[1180627629] 'read index received' (duration: 244.61379ms)","trace[1180627629] 'applied index is now lower than readState.Index' (duration: 200.575µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:19.334337Z","caller":"traceutil/trace.go:171","msg":"trace[613690637] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"283.788085ms","start":"2026-05-31T05:51:19.050537Z","end":"2026-05-31T05:51:19.334325Z","steps":["trace[613690637] 'process raft request' (duration: 283.540939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:19.334498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.182387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-31T05:51:19.334615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.203175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:51:19.334645Z","caller":"traceutil/trace.go:171","msg":"trace[616563136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"146.375462ms","start":"2026-05-31T05:51:19.188249Z","end":"2026-05-31T05:51:19.334625Z","steps":["trace[616563136] 'agreement among raft nodes before linearized reading' (duration: 146.112196ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:19.334654Z","caller":"traceutil/trace.go:171","msg":"trace[438296007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:448; }","duration":"245.318777ms","start":"2026-05-31T05:51:19.089325Z","end":"2026-05-31T05:51:19.334643Z","steps":["trace[438296007] 'agreement among raft nodes before linearized reading' (duration: 245.207945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:19.334723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.347271ms","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-05-31T05:51:19.334813Z","caller":"traceutil/trace.go:171","msg":"trace[1988868062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"146.479455ms","start":"2026-05-31T05:51:19.188313Z","end":"2026-05-31T05:51:19.334793Z","steps":["trace[1988868062] 'agreement among raft nodes before linearized reading' (duration: 146.28213ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:19.475924Z","caller":"traceutil/trace.go:171","msg":"trace[1610421732] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"135.646769ms","start":"2026-05-31T05:51:19.340244Z","end":"2026-05-31T05:51:19.475891Z","steps":["trace[1610421732] 'process raft request' (duration: 135.467014ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:19.476114Z","caller":"traceutil/trace.go:171","msg":"trace[300269644] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"135.801653ms","start":"2026-05-31T05:51:19.340277Z","end":"2026-05-31T05:51:19.476079Z","steps":["trace[300269644] 'process raft request' (duration: 135.567417ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:19.704539Z","caller":"traceutil/trace.go:171","msg":"trace[1814731734] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"134.961132ms","start":"2026-05-31T05:51:19.56955Z","end":"2026-05-31T05:51:19.704511Z","steps":["trace[1814731734] 'process raft request' (duration: 134.530521ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:19.896143Z","caller":"traceutil/trace.go:171","msg":"trace[117177706] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"123.46675ms","start":"2026-05-31T05:51:19.772651Z","end":"2026-05-31T05:51:19.896118Z","steps":["trace[117177706] 'process raft request' (duration: 111.384282ms)","trace[117177706] 'compare' (duration: 11.946095ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:21.568527Z","caller":"traceutil/trace.go:171","msg":"trace[506629399] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"164.464508ms","start":"2026-05-31T05:51:21.404046Z","end":"2026-05-31T05:51:21.56851Z","steps":["trace[506629399] 'read index received' (duration: 164.314543ms)","trace[506629399] 'applied index is now lower than readState.Index' (duration: 149.325µs)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:21.5688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.741785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-31T05:51:21.568833Z","caller":"traceutil/trace.go:171","msg":"trace[656497849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"164.834878ms","start":"2026-05-31T05:51:21.40399Z","end":"2026-05-31T05:51:21.568825Z","steps":["trace[656497849] 'agreement among raft nodes before linearized reading' (duration: 164.763276ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:21.568974Z","caller":"traceutil/trace.go:171","msg":"trace[1618818107] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"226.878614ms","start":"2026-05-31T05:51:21.342089Z","end":"2026-05-31T05:51:21.568967Z","steps":["trace[1618818107] 'process raft request' (duration: 226.32237ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:22.136317Z","caller":"traceutil/trace.go:171","msg":"trace[1209645393] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"394.89064ms","start":"2026-05-31T05:51:21.741403Z","end":"2026-05-31T05:51:22.136294Z","steps":["trace[1209645393] 'process raft request' (duration: 394.765987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:22.136459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.74138Z","time spent":"395.012872ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:22.196126Z","caller":"traceutil/trace.go:171","msg":"trace[136840420] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"277.955202ms","start":"2026-05-31T05:51:21.918145Z","end":"2026-05-31T05:51:22.1961Z","steps":["trace[136840420] 'process raft request' (duration: 277.893901ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:22.196239Z","caller":"traceutil/trace.go:171","msg":"trace[1365692436] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"421.477134ms","start":"2026-05-31T05:51:21.774719Z","end":"2026-05-31T05:51:22.196196Z","steps":["trace[1365692436] 'process raft request' (duration: 420.929629ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:22.196308Z","caller":"traceutil/trace.go:171","msg":"trace[1609100444] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"397.287098ms","start":"2026-05-31T05:51:21.798983Z","end":"2026-05-31T05:51:22.19627Z","steps":["trace[1609100444] 'process raft request' (duration: 396.793915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:22.196375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.774711Z","time spent":"421.599077ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:22.196376Z","caller":"traceutil/trace.go:171","msg":"trace[920459600] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"436.919243ms","start":"2026-05-31T05:51:21.759434Z","end":"2026-05-31T05:51:22.196354Z","steps":["trace[920459600] 'process raft request' (duration: 436.106993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:22.196434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.798956Z","time spent":"397.423101ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:51:22.196488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.759422Z","time spent":"437.001845ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:22.196583Z","caller":"traceutil/trace.go:171","msg":"trace[1853255533] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"423.929515ms","start":"2026-05-31T05:51:21.772641Z","end":"2026-05-31T05:51:22.196571Z","steps":["trace[1853255533] 'process raft request' (duration: 422.9448ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:22.19659Z","caller":"traceutil/trace.go:171","msg":"trace[1221434223] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"413.779014ms","start":"2026-05-31T05:51:21.782798Z","end":"2026-05-31T05:51:22.196577Z","steps":["trace[1221434223] 'process raft request' (duration: 412.894321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:22.196632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.77262Z","time spent":"423.991416ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:51:22.19664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.78278Z","time spent":"413.836656ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:22.196662Z","caller":"traceutil/trace.go:171","msg":"trace[662596359] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"452.050267ms","start":"2026-05-31T05:51:21.744601Z","end":"2026-05-31T05:51:22.196651Z","steps":["trace[662596359] 'process raft request' (duration: 450.747814ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:22.196566Z","caller":"traceutil/trace.go:171","msg":"trace[405853341] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"441.607699ms","start":"2026-05-31T05:51:21.75473Z","end":"2026-05-31T05:51:22.196338Z","steps":["trace[405853341] 'process raft request' (duration: 440.760018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:22.196724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.744574Z","time spent":"452.122629ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:22.196739Z","caller":"traceutil/trace.go:171","msg":"trace[1834498065] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"377.578574ms","start":"2026-05-31T05:51:21.819152Z","end":"2026-05-31T05:51:22.19673Z","steps":["trace[1834498065] 'process raft request' (duration: 376.823445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:22.19678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.81914Z","time spent":"377.619404ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:22.196785Z","caller":"traceutil/trace.go:171","msg":"trace[887295521] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"396.265853ms","start":"2026-05-31T05:51:21.800045Z","end":"2026-05-31T05:51:22.196311Z","steps":["trace[887295521] 'process raft request' (duration: 395.873084ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:22.196763Z","caller":"traceutil/trace.go:171","msg":"trace[245122727] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"411.692282ms","start":"2026-05-31T05:51:21.78499Z","end":"2026-05-31T05:51:22.196683Z","steps":["trace[245122727] 'process raft request' (duration: 410.744348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:22.196866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.800025Z","time spent":"396.794767ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:51:22.196968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.754721Z","time spent":"442.001828ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:51:22.197112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:21.784981Z","time spent":"411.920149ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:51:22.825353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.517647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:51:22.825463Z","caller":"traceutil/trace.go:171","msg":"trace[2117250180] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"206.344288ms","start":"2026-05-31T05:51:22.6191Z","end":"2026-05-31T05:51:22.825445Z","steps":["trace[2117250180] 'process raft request' (duration: 87.543065ms)","trace[2117250180] 'compare' (duration: 118.358113ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:22.924435Z","caller":"traceutil/trace.go:171","msg":"trace[85137907] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"278.940666ms","start":"2026-05-31T05:51:22.645472Z","end":"2026-05-31T05:51:22.924413Z","steps":["trace[85137907] 'process raft request' (duration: 278.724489ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:23.232108Z","caller":"traceutil/trace.go:171","msg":"trace[1991687638] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"379.630544ms","start":"2026-05-31T05:51:22.852449Z","end":"2026-05-31T05:51:23.23208Z","steps":["trace[1991687638] 'process raft request' (duration: 379.272345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:23.232337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:22.852419Z","time spent":"379.811738ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:23.232646Z","caller":"traceutil/trace.go:171","msg":"trace[670338025] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"379.682946ms","start":"2026-05-31T05:51:22.852954Z","end":"2026-05-31T05:51:23.232637Z","steps":["trace[670338025] 'process raft request' (duration: 379.433289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:23.232691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:22.852939Z","time spent":"379.732737ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:51:23.629346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.030074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:51:23.629549Z","caller":"traceutil/trace.go:171","msg":"trace[515615605] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"684.477306ms","start":"2026-05-31T05:51:22.945042Z","end":"2026-05-31T05:51:23.629519Z","steps":["trace[515615605] 'process raft request' (duration: 473.182857ms)","trace[515615605] 'compare' (duration: 210.90882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:23.629694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:22.945019Z","time spent":"684.598959ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:23.642797Z","caller":"traceutil/trace.go:171","msg":"trace[418796102] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"371.698159ms","start":"2026-05-31T05:51:23.271082Z","end":"2026-05-31T05:51:23.64278Z","steps":["trace[418796102] 'read index received' (duration: 147.156173ms)","trace[418796102] 'applied index is now lower than readState.Index' (duration: 224.540026ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:23.642924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.826322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:23.64297Z","caller":"traceutil/trace.go:171","msg":"trace[820960849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"371.906143ms","start":"2026-05-31T05:51:23.271053Z","end":"2026-05-31T05:51:23.642959Z","steps":["trace[820960849] 'agreement among raft nodes before linearized reading' (duration: 371.821571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:23.642993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:23.271038Z","time spent":"371.950615ms","remote":"127.0.0.1:34400","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-05-31T05:51:23.642991Z","caller":"traceutil/trace.go:171","msg":"trace[875912503] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"295.151914ms","start":"2026-05-31T05:51:23.347822Z","end":"2026-05-31T05:51:23.642974Z","steps":["trace[875912503] 'process raft request' (duration: 295.092872ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:23.643255Z","caller":"traceutil/trace.go:171","msg":"trace[121739549] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"371.997196ms","start":"2026-05-31T05:51:23.271248Z","end":"2026-05-31T05:51:23.643245Z","steps":["trace[121739549] 'process raft request' (duration: 371.480304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:23.643428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:23.271237Z","time spent":"372.047597ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:24.424172Z","caller":"traceutil/trace.go:171","msg":"trace[1654502414] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"278.202247ms","start":"2026-05-31T05:51:24.145956Z","end":"2026-05-31T05:51:24.424158Z","steps":["trace[1654502414] 'process raft request' (duration: 273.424819ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:24.42411Z","caller":"traceutil/trace.go:171","msg":"trace[1023936227] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"254.675838ms","start":"2026-05-31T05:51:24.169408Z","end":"2026-05-31T05:51:24.424084Z","steps":["trace[1023936227] 'read index received' (duration: 249.936201ms)","trace[1023936227] 'applied index is now lower than readState.Index' (duration: 4.738637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:24.424559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.947799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:24.424621Z","caller":"traceutil/trace.go:171","msg":"trace[168853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"154.050632ms","start":"2026-05-31T05:51:24.270558Z","end":"2026-05-31T05:51:24.424609Z","steps":["trace[168853] 'agreement among raft nodes before linearized reading' (duration: 153.899778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:24.425169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.731023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-05-31T05:51:24.425257Z","caller":"traceutil/trace.go:171","msg":"trace[550438213] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:568; }","duration":"255.864297ms","start":"2026-05-31T05:51:24.169373Z","end":"2026-05-31T05:51:24.425237Z","steps":["trace[550438213] 'agreement among raft nodes before linearized reading' (duration: 254.887633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:29.772563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.775806ms","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-05-31T05:51:29.772793Z","caller":"traceutil/trace.go:171","msg":"trace[872550977] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:599; }","duration":"228.014802ms","start":"2026-05-31T05:51:29.544756Z","end":"2026-05-31T05:51:29.772771Z","steps":["trace[872550977] 'range keys from in-memory index tree' (duration: 227.757756ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:29.917293Z","caller":"traceutil/trace.go:171","msg":"trace[1977424535] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"241.146565ms","start":"2026-05-31T05:51:29.676118Z","end":"2026-05-31T05:51:29.917265Z","steps":["trace[1977424535] 'read index received' (duration: 240.365846ms)","trace[1977424535] 'applied index is now lower than readState.Index' (duration: 779.859µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:29.917411Z","caller":"traceutil/trace.go:171","msg":"trace[273615841] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"468.901881ms","start":"2026-05-31T05:51:29.448474Z","end":"2026-05-31T05:51:29.917376Z","steps":["trace[273615841] 'process raft request' (duration: 468.276946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:29.917555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.417342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:51:29.917638Z","caller":"traceutil/trace.go:171","msg":"trace[1496296211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"241.516174ms","start":"2026-05-31T05:51:29.676101Z","end":"2026-05-31T05:51:29.917617Z","steps":["trace[1496296211] 'agreement among raft nodes before linearized reading' (duration: 241.260228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:29.91769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.221569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-31T05:51:29.917691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.297301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-31T05:51:29.917687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:29.448453Z","time spent":"469.108377ms","remote":"127.0.0.1:34494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:29.917745Z","caller":"traceutil/trace.go:171","msg":"trace[420189665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"235.343213ms","start":"2026-05-31T05:51:29.682388Z","end":"2026-05-31T05:51:29.917731Z","steps":["trace[420189665] 'agreement among raft nodes before linearized reading' (duration: 235.166768ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:29.917755Z","caller":"traceutil/trace.go:171","msg":"trace[1875939785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"235.409734ms","start":"2026-05-31T05:51:29.682329Z","end":"2026-05-31T05:51:29.917739Z","steps":["trace[1875939785] 'agreement among raft nodes before linearized reading' (duration: 235.24911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:29.917939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.984034ms","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-05-31T05:51:29.917974Z","caller":"traceutil/trace.go:171","msg":"trace[41310674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"237.063275ms","start":"2026-05-31T05:51:29.680901Z","end":"2026-05-31T05:51:29.917964Z","steps":["trace[41310674] 'agreement among raft nodes before linearized reading' (duration: 236.521072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:31.559421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.266838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:51:31.559505Z","caller":"traceutil/trace.go:171","msg":"trace[700765651] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.637509072s","start":"2026-05-31T05:51:29.921981Z","end":"2026-05-31T05:51:31.55949Z","steps":["trace[700765651] 'process raft request' (duration: 678.09799ms)","trace[700765651] 'compare' (duration: 958.752846ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:31.559776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:29.921957Z","time spent":"1.637582924s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:51:31.890596Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.290547402s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:51:31.969571Z","caller":"traceutil/trace.go:171","msg":"trace[971384093] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"2.047050251s","start":"2026-05-31T05:51:29.922477Z","end":"2026-05-31T05:51:31.969527Z","steps":["trace[971384093] 'process raft request' (duration: 1.968352994s)","trace[971384093] 'compare' (duration: 78.482062ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:31.969949Z","caller":"traceutil/trace.go:171","msg":"trace[1264940200] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"2.044665834s","start":"2026-05-31T05:51:29.925259Z","end":"2026-05-31T05:51:31.969925Z","steps":["trace[1264940200] 'process raft request' (duration: 2.044222642s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:31.969998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:29.922447Z","time spent":"2.047224086s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:51:31.970091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:29.92524Z","time spent":"2.044780216s","remote":"127.0.0.1:34700","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-05-31T05:51:31.970498Z","caller":"traceutil/trace.go:171","msg":"trace[774343436] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.124871605s","start":"2026-05-31T05:51:30.845613Z","end":"2026-05-31T05:51:31.970484Z","steps":["trace[774343436] 'process raft request' (duration: 1.124823434s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:31.970605Z","caller":"traceutil/trace.go:171","msg":"trace[1261282264] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"2.045102074s","start":"2026-05-31T05:51:29.925484Z","end":"2026-05-31T05:51:31.970586Z","steps":["trace[1261282264] 'process raft request' (duration: 2.044349904s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:31.97071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699758954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:51:31.970716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:29.925431Z","time spent":"2.045230557s","remote":"127.0.0.1:34700","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-05-31T05:51:31.970777Z","caller":"traceutil/trace.go:171","msg":"trace[1200910204] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"2.002604058s","start":"2026-05-31T05:51:29.968162Z","end":"2026-05-31T05:51:31.970766Z","steps":["trace[1200910204] 'process raft request' (duration: 2.002189398s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:31.970835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:29.968144Z","time spent":"2.00266318s","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:31.970619Z","caller":"traceutil/trace.go:171","msg":"trace[1454964099] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:623; }","duration":"1.699651691s","start":"2026-05-31T05:51:30.270953Z","end":"2026-05-31T05:51:31.970604Z","steps":["trace[1454964099] 'read index received' (duration: 329.12021ms)","trace[1454964099] 'applied index is now lower than readState.Index' (duration: 1.370528511s)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:31.970745Z","caller":"traceutil/trace.go:171","msg":"trace[1809816734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"1.699842526s","start":"2026-05-31T05:51:30.270893Z","end":"2026-05-31T05:51:31.970735Z","steps":["trace[1809816734] 'agreement among raft nodes before linearized reading' (duration: 1.699760194s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:31.970939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:30.27087Z","time spent":"1.700053861s","remote":"127.0.0.1:34400","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-05-31T05:51:31.97058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:30.845588Z","time spent":"1.124940577s","remote":"127.0.0.1:34700","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-05-31T05:51:33.14979Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.16407664s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:51:33.173738Z","caller":"traceutil/trace.go:171","msg":"trace[376066556] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"1.188081981s","start":"2026-05-31T05:51:31.985633Z","end":"2026-05-31T05:51:33.173715Z","steps":["trace[376066556] 'process raft request' (duration: 1.164335527s)","trace[376066556] 'compare' (duration: 22.971355ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:33.173835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:31.985623Z","time spent":"1.188168624s","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-31T05:51:33.770834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400623952,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:51:33.833647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.073336ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-31T05:51:33.834276Z","caller":"traceutil/trace.go:171","msg":"trace[1100756119] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"656.169109ms","start":"2026-05-31T05:51:33.178088Z","end":"2026-05-31T05:51:33.834257Z","steps":["trace[1100756119] 'process raft request' (duration: 655.646277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:33.834615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:33.178059Z","time spent":"656.489147ms","remote":"127.0.0.1:34466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:33.894843Z","caller":"traceutil/trace.go:171","msg":"trace[1063019565] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"715.260655ms","start":"2026-05-31T05:51:33.179553Z","end":"2026-05-31T05:51:33.894814Z","steps":["trace[1063019565] 'process raft request' (duration: 714.918207ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:33.894886Z","caller":"traceutil/trace.go:171","msg":"trace[166474479] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"715.215983ms","start":"2026-05-31T05:51:33.179649Z","end":"2026-05-31T05:51:33.894865Z","steps":["trace[166474479] 'process raft request' (duration: 715.008928ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:33.894963Z","caller":"traceutil/trace.go:171","msg":"trace[1083328680] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"714.510825ms","start":"2026-05-31T05:51:33.180439Z","end":"2026-05-31T05:51:33.89495Z","steps":["trace[1083328680] 'process raft request' (duration: 714.307311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:33.894989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:33.179635Z","time spent":"715.282746ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:51:33.894989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.45137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:51:33.895029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:33.180421Z","time spent":"714.565997ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:33.895049Z","caller":"traceutil/trace.go:171","msg":"trace[520394615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"624.553941ms","start":"2026-05-31T05:51:33.270475Z","end":"2026-05-31T05:51:33.895029Z","steps":["trace[520394615] 'agreement among raft nodes before linearized reading' (duration: 624.427998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:33.895125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:33.270458Z","time spent":"624.650604ms","remote":"127.0.0.1:34400","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-05-31T05:51:33.894836Z","caller":"traceutil/trace.go:171","msg":"trace[53662497] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:630; }","duration":"624.290585ms","start":"2026-05-31T05:51:33.270522Z","end":"2026-05-31T05:51:33.894812Z","steps":["trace[53662497] 'read index received' (duration: 531.012349ms)","trace[53662497] 'applied index is now lower than readState.Index' (duration: 93.276866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:33.895306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.31922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:51:33.895327Z","caller":"traceutil/trace.go:171","msg":"trace[1987562798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"330.371171ms","start":"2026-05-31T05:51:33.564949Z","end":"2026-05-31T05:51:33.89532Z","steps":["trace[1987562798] 'agreement among raft nodes before linearized reading' (duration: 330.271869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:33.895342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:33.564928Z","time spent":"330.410182ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-31T05:51:33.895418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:33.179539Z","time spent":"715.378358ms","remote":"127.0.0.1:34466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:51:34.268702Z","caller":"traceutil/trace.go:171","msg":"trace[420604544] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"168.276261ms","start":"2026-05-31T05:51:34.100404Z","end":"2026-05-31T05:51:34.26868Z","steps":["trace[420604544] 'process raft request' (duration: 168.142068ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:35.556597Z","caller":"traceutil/trace.go:171","msg":"trace[1720685787] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"434.028863ms","start":"2026-05-31T05:51:35.122545Z","end":"2026-05-31T05:51:35.556574Z","steps":["trace[1720685787] 'process raft request' (duration: 433.845768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:35.556819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:35.122531Z","time spent":"434.208727ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:35.559914Z","caller":"traceutil/trace.go:171","msg":"trace[1804261300] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"288.857089ms","start":"2026-05-31T05:51:35.271035Z","end":"2026-05-31T05:51:35.559892Z","steps":["trace[1804261300] 'read index received' (duration: 285.368494ms)","trace[1804261300] 'applied index is now lower than readState.Index' (duration: 3.487655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:35.560075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.034884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:35.560125Z","caller":"traceutil/trace.go:171","msg":"trace[1479746842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"289.121956ms","start":"2026-05-31T05:51:35.270993Z","end":"2026-05-31T05:51:35.560115Z","steps":["trace[1479746842] 'agreement among raft nodes before linearized reading' (duration: 289.042584ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:35.560155Z","caller":"traceutil/trace.go:171","msg":"trace[2016470405] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"435.56509ms","start":"2026-05-31T05:51:35.124369Z","end":"2026-05-31T05:51:35.559934Z","steps":["trace[2016470405] 'process raft request' (duration: 435.343395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:35.560237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.565809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:35.560292Z","caller":"traceutil/trace.go:171","msg":"trace[560405705] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:641; }","duration":"270.61723ms","start":"2026-05-31T05:51:35.289653Z","end":"2026-05-31T05:51:35.56027Z","steps":["trace[560405705] 'agreement among raft nodes before linearized reading' (duration: 270.567898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:35.560187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.963411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-b5m7g\" ","response":"range_response_count:1 size:4884"} {"level":"warn","ts":"2026-05-31T05:51:35.560402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:35.12436Z","time spent":"435.883538ms","remote":"127.0.0.1:34442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:35.56046Z","caller":"traceutil/trace.go:171","msg":"trace[1515682740] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-b5m7g; range_end:; response_count:1; response_revision:641; }","duration":"278.262058ms","start":"2026-05-31T05:51:35.282158Z","end":"2026-05-31T05:51:35.56042Z","steps":["trace[1515682740] 'agreement among raft nodes before linearized reading' (duration: 277.891979ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:43.397898Z","caller":"traceutil/trace.go:171","msg":"trace[441129228] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"256.128035ms","start":"2026-05-31T05:51:43.141749Z","end":"2026-05-31T05:51:43.397877Z","steps":["trace[441129228] 'process raft request' (duration: 255.981021ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:43.473817Z","caller":"traceutil/trace.go:171","msg":"trace[1972021164] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"278.821613ms","start":"2026-05-31T05:51:43.194975Z","end":"2026-05-31T05:51:43.473797Z","steps":["trace[1972021164] 'process raft request' (duration: 263.959798ms)","trace[1972021164] 'compare' (duration: 14.736972ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:43.524741Z","caller":"traceutil/trace.go:171","msg":"trace[1224874172] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"253.759225ms","start":"2026-05-31T05:51:43.270951Z","end":"2026-05-31T05:51:43.524711Z","steps":["trace[1224874172] 'read index received' (duration: 127.105528ms)","trace[1224874172] 'applied index is now lower than readState.Index' (duration: 126.652447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:43.524846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.875458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:43.524864Z","caller":"traceutil/trace.go:171","msg":"trace[1320440278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"253.940089ms","start":"2026-05-31T05:51:43.270919Z","end":"2026-05-31T05:51:43.524859Z","steps":["trace[1320440278] 'agreement among raft nodes before linearized reading' (duration: 253.878738ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:44.614731Z","caller":"traceutil/trace.go:171","msg":"trace[1224355842] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"150.816402ms","start":"2026-05-31T05:51:44.463886Z","end":"2026-05-31T05:51:44.614702Z","steps":["trace[1224355842] 'process raft request' (duration: 150.31026ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:44.629154Z","caller":"traceutil/trace.go:171","msg":"trace[1305217557] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"159.70369ms","start":"2026-05-31T05:51:44.469432Z","end":"2026-05-31T05:51:44.629136Z","steps":["trace[1305217557] 'process raft request' (duration: 159.585557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:49.7493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400624264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:51:50.250392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400624264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:51:50.751478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400624264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:51:51.252236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400624264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:51:51.270893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000300614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-31T05:51:51.270974Z","caller":"traceutil/trace.go:171","msg":"trace[142624315] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000423746s","start":"2026-05-31T05:51:49.270532Z","end":"2026-05-31T05:51:51.270956Z","steps":["trace[142624315] 'agreement among raft nodes before linearized reading' (duration: 2.000326103s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:51.271026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:49.270514Z","time spent":"2.000500228s","remote":"127.0.0.1:34400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/31 05:51:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-31T05:51:51.753028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400624264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:51:52.25335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400624264,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:51:52.424815Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.2552081s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:51:52.425286Z","caller":"traceutil/trace.go:171","msg":"trace[2050296030] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"3.255782744s","start":"2026-05-31T05:51:49.169482Z","end":"2026-05-31T05:51:52.425264Z","steps":["trace[2050296030] 'process raft request' (duration: 3.255531348s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.425465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:49.169461Z","time spent":"3.255879546s","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:52.742067Z","caller":"traceutil/trace.go:171","msg":"trace[1925945992] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"1.588718833s","start":"2026-05-31T05:51:51.153318Z","end":"2026-05-31T05:51:52.742036Z","steps":["trace[1925945992] 'process raft request' (duration: 1.491717246s)","trace[1925945992] 'compare' (duration: 96.476744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:52.742231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:51.15328Z","time spent":"1.588868378s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5051,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:52.74295Z","caller":"traceutil/trace.go:171","msg":"trace[140522625] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:797; }","duration":"3.494096849s","start":"2026-05-31T05:51:49.248831Z","end":"2026-05-31T05:51:52.742927Z","steps":["trace[140522625] 'read index received' (duration: 3.176183904s)","trace[140522625] 'applied index is now lower than readState.Index' (duration: 317.911895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:52.743167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.338222954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:51:52.743168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.494325015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:52.74322Z","caller":"traceutil/trace.go:171","msg":"trace[193469921] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:772; }","duration":"3.338321586s","start":"2026-05-31T05:51:49.404887Z","end":"2026-05-31T05:51:52.743208Z","steps":["trace[193469921] 'agreement among raft nodes before linearized reading' (duration: 3.338238334s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.743315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.583920157s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-31T05:51:52.743362Z","caller":"traceutil/trace.go:171","msg":"trace[216556580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"2.584049561s","start":"2026-05-31T05:51:50.159302Z","end":"2026-05-31T05:51:52.743352Z","steps":["trace[216556580] 'agreement among raft nodes before linearized reading' (duration: 2.583842546s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.743406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.159292Z","time spent":"2.584105222s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-31T05:51:52.743406Z","caller":"traceutil/trace.go:171","msg":"trace[479658458] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"365.791534ms","start":"2026-05-31T05:51:52.377603Z","end":"2026-05-31T05:51:52.743394Z","steps":["trace[479658458] 'process raft request' (duration: 364.393939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.743652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.591843003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-31T05:51:52.743676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472369429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:52.743716Z","caller":"traceutil/trace.go:171","msg":"trace[789195869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"2.591947235s","start":"2026-05-31T05:51:50.151755Z","end":"2026-05-31T05:51:52.743702Z","steps":["trace[789195869] 'agreement among raft nodes before linearized reading' (duration: 2.59171954s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:52.743722Z","caller":"traceutil/trace.go:171","msg":"trace[590885538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"1.472461921s","start":"2026-05-31T05:51:51.271247Z","end":"2026-05-31T05:51:52.743709Z","steps":["trace[590885538] 'agreement among raft nodes before linearized reading' (duration: 1.47239121s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.743716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.303244672s","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-05-31T05:51:52.74375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:51.271155Z","time spent":"1.472589304s","remote":"127.0.0.1:34384","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-05-31T05:51:52.743786Z","caller":"traceutil/trace.go:171","msg":"trace[960371027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"3.303348045s","start":"2026-05-31T05:51:49.440422Z","end":"2026-05-31T05:51:52.74377Z","steps":["trace[960371027] 'agreement among raft nodes before linearized reading' (duration: 3.303179671s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.743753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.428701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:51:52.74384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:49.440403Z","time spent":"3.303425606s","remote":"127.0.0.1:34700","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-05-31T05:51:52.743788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.240849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:52.743883Z","caller":"traceutil/trace.go:171","msg":"trace[2065109986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"538.613876ms","start":"2026-05-31T05:51:52.205241Z","end":"2026-05-31T05:51:52.743855Z","steps":["trace[2065109986] 'agreement among raft nodes before linearized reading' (duration: 538.416771ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:52.743921Z","caller":"traceutil/trace.go:171","msg":"trace[832715298] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:772; }","duration":"664.407573ms","start":"2026-05-31T05:51:52.079502Z","end":"2026-05-31T05:51:52.74391Z","steps":["trace[832715298] 'agreement among raft nodes before linearized reading' (duration: 664.26449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.743424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:49.404868Z","time spent":"3.338530951s","remote":"127.0.0.1:34730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:51:52.74326Z","caller":"traceutil/trace.go:171","msg":"trace[1764786349] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:772; }","duration":"3.494464099s","start":"2026-05-31T05:51:49.248782Z","end":"2026-05-31T05:51:52.743246Z","steps":["trace[1764786349] 'agreement among raft nodes before linearized reading' (duration: 3.494292845s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.743951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:52.205216Z","time spent":"538.722119ms","remote":"127.0.0.1:34400","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-05-31T05:51:52.743983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:52.079479Z","time spent":"664.490645ms","remote":"127.0.0.1:34916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:51:52.743997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:49.24876Z","time spent":"3.495223898s","remote":"127.0.0.1:34576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:51:52.743747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.151723Z","time spent":"2.592016607s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-31T05:51:52.743582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:52.377576Z","time spent":"365.905316ms","remote":"127.0.0.1:34700","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-05-31T05:51:52.743954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.601728496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-31T05:51:52.744268Z","caller":"traceutil/trace.go:171","msg":"trace[661746577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"2.602070775s","start":"2026-05-31T05:51:50.14218Z","end":"2026-05-31T05:51:52.74425Z","steps":["trace[661746577] 'agreement among raft nodes before linearized reading' (duration: 2.601520471s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.744387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:50.142152Z","time spent":"2.602215708s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-31T05:51:52.744658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.758875594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:51:52.74481Z","caller":"traceutil/trace.go:171","msg":"trace[122786513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"2.759080548s","start":"2026-05-31T05:51:49.985714Z","end":"2026-05-31T05:51:52.744795Z","steps":["trace[122786513] 'agreement among raft nodes before linearized reading' (duration: 2.757815757s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:52.744992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:49.985696Z","time spent":"2.759282174s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-31T05:51:52.852127Z","caller":"traceutil/trace.go:171","msg":"trace[206187989] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:801; }","duration":"100.32236ms","start":"2026-05-31T05:51:52.751786Z","end":"2026-05-31T05:51:52.852108Z","steps":["trace[206187989] 'read index received' (duration: 14.066256ms)","trace[206187989] 'applied index is now lower than readState.Index' (duration: 86.255314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:52.852288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.488063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:51:52.852329Z","caller":"traceutil/trace.go:171","msg":"trace[1747638035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"100.557535ms","start":"2026-05-31T05:51:52.751758Z","end":"2026-05-31T05:51:52.852315Z","steps":["trace[1747638035] 'agreement among raft nodes before linearized reading' (duration: 100.436632ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:52.852442Z","caller":"traceutil/trace.go:171","msg":"trace[798909037] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"101.426387ms","start":"2026-05-31T05:51:52.750971Z","end":"2026-05-31T05:51:52.852397Z","steps":["trace[798909037] 'process raft request' (duration: 100.903294ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:52.852496Z","caller":"traceutil/trace.go:171","msg":"trace[1935548495] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"101.427396ms","start":"2026-05-31T05:51:52.75104Z","end":"2026-05-31T05:51:52.852468Z","steps":["trace[1935548495] 'process raft request' (duration: 101.008846ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:52.85266Z","caller":"traceutil/trace.go:171","msg":"trace[1586100192] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"101.718273ms","start":"2026-05-31T05:51:52.750932Z","end":"2026-05-31T05:51:52.852651Z","steps":["trace[1586100192] 'process raft request' (duration: 92.869385ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:52.852683Z","caller":"traceutil/trace.go:171","msg":"trace[770449947] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"101.675612ms","start":"2026-05-31T05:51:52.750992Z","end":"2026-05-31T05:51:52.852667Z","steps":["trace[770449947] 'process raft request' (duration: 100.928334ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:59.662315Z","caller":"traceutil/trace.go:171","msg":"trace[1357579662] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"144.321582ms","start":"2026-05-31T05:51:59.517967Z","end":"2026-05-31T05:51:59.662289Z","steps":["trace[1357579662] 'process raft request' (duration: 136.488919ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:59.878812Z","caller":"traceutil/trace.go:171","msg":"trace[1072648346] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"360.497263ms","start":"2026-05-31T05:51:59.518267Z","end":"2026-05-31T05:51:59.878764Z","steps":["trace[1072648346] 'process raft request' (duration: 329.681324ms)","trace[1072648346] 'compare' (duration: 30.425389ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:51:59.878787Z","caller":"traceutil/trace.go:171","msg":"trace[1558699837] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:875; }","duration":"211.333951ms","start":"2026-05-31T05:51:59.667414Z","end":"2026-05-31T05:51:59.878748Z","steps":["trace[1558699837] 'read index received' (duration: 180.513352ms)","trace[1558699837] 'applied index is now lower than readState.Index' (duration: 30.818939ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:51:59.878988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:59.518254Z","time spent":"360.648677ms","remote":"127.0.0.1:34606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:51:59.87908Z","caller":"traceutil/trace.go:171","msg":"trace[166727084] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"360.00834ms","start":"2026-05-31T05:51:59.519049Z","end":"2026-05-31T05:51:59.879057Z","steps":["trace[166727084] 'process raft request' (duration: 359.58816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:51:59.879207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:51:59.519028Z","time spent":"360.112013ms","remote":"127.0.0.1:60592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2316,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:51:59.879295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.841334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-4kpg2\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-05-31T05:51:59.879349Z","caller":"traceutil/trace.go:171","msg":"trace[200197030] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-4kpg2; range_end:; response_count:1; response_revision:846; }","duration":"211.984127ms","start":"2026-05-31T05:51:59.667347Z","end":"2026-05-31T05:51:59.879331Z","steps":["trace[200197030] 'agreement among raft nodes before linearized reading' (duration: 211.741851ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:51:59.919999Z","caller":"traceutil/trace.go:171","msg":"trace[458261970] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"252.431992ms","start":"2026-05-31T05:51:59.667541Z","end":"2026-05-31T05:51:59.919973Z","steps":["trace[458261970] 'process raft request' (duration: 252.296899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:28.181042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.529158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:28.181126Z","caller":"traceutil/trace.go:171","msg":"trace[1681742425] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:985; }","duration":"799.6491ms","start":"2026-05-31T05:52:27.381456Z","end":"2026-05-31T05:52:28.181105Z","steps":["trace[1681742425] 'count revisions from in-memory index tree' (duration: 799.471356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:28.18117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:27.381426Z","time spent":"799.733733ms","remote":"127.0.0.1:51606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:52:28.18129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.56667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:52:28.18139Z","caller":"traceutil/trace.go:171","msg":"trace[819827678] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"204.681331ms","start":"2026-05-31T05:52:27.976692Z","end":"2026-05-31T05:52:28.181373Z","steps":["trace[819827678] 'read index received' (duration: 58.907156ms)","trace[819827678] 'applied index is now lower than readState.Index' (duration: 145.772835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:28.181547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.862305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-31T05:52:28.181575Z","caller":"traceutil/trace.go:171","msg":"trace[455099747] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:986; }","duration":"204.919616ms","start":"2026-05-31T05:52:27.976646Z","end":"2026-05-31T05:52:28.181565Z","steps":["trace[455099747] 'agreement among raft nodes before linearized reading' (duration: 204.772813ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:28.181578Z","caller":"traceutil/trace.go:171","msg":"trace[351303567] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"768.851189ms","start":"2026-05-31T05:52:27.412703Z","end":"2026-05-31T05:52:28.181554Z","steps":["trace[351303567] 'process raft request' (duration: 622.936961ms)","trace[351303567] 'compare' (duration: 145.397876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:28.181736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:27.412679Z","time spent":"768.957981ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:28.641644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.330857ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-31T05:52:28.641748Z","caller":"traceutil/trace.go:171","msg":"trace[294736240] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"411.839432ms","start":"2026-05-31T05:52:28.22989Z","end":"2026-05-31T05:52:28.641729Z","steps":["trace[294736240] 'read index received' (duration: 102.408482ms)","trace[294736240] 'applied index is now lower than readState.Index' (duration: 309.42943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:28.641879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.657255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:52:28.641833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:28.183368Z","time spent":"458.456826ms","remote":"127.0.0.1:34424","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-31T05:52:28.641915Z","caller":"traceutil/trace.go:171","msg":"trace[641289536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"370.726247ms","start":"2026-05-31T05:52:28.27118Z","end":"2026-05-31T05:52:28.641906Z","steps":["trace[641289536] 'agreement among raft nodes before linearized reading' (duration: 370.658915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:28.641951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:28.271164Z","time spent":"370.779887ms","remote":"127.0.0.1:34400","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-05-31T05:52:28.641992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.082749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T05:52:28.642065Z","caller":"traceutil/trace.go:171","msg":"trace[1384004084] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:986; }","duration":"412.208922ms","start":"2026-05-31T05:52:28.22984Z","end":"2026-05-31T05:52:28.642049Z","steps":["trace[1384004084] 'agreement among raft nodes before linearized reading' (duration: 411.979826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:28.642128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:28.229823Z","time spent":"412.298195ms","remote":"127.0.0.1:34924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:52:29.293543Z","caller":"traceutil/trace.go:171","msg":"trace[1121712634] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"650.573854ms","start":"2026-05-31T05:52:28.642944Z","end":"2026-05-31T05:52:29.293518Z","steps":["trace[1121712634] 'process raft request' (duration: 650.373269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:29.293704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:28.642922Z","time spent":"650.710838ms","remote":"127.0.0.1:34424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:52:29.321853Z","caller":"traceutil/trace.go:171","msg":"trace[1375748576] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1024; }","duration":"323.266612ms","start":"2026-05-31T05:52:28.998563Z","end":"2026-05-31T05:52:29.321829Z","steps":["trace[1375748576] 'read index received' (duration: 294.84908ms)","trace[1375748576] 'applied index is now lower than readState.Index' (duration: 28.416602ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:29.321862Z","caller":"traceutil/trace.go:171","msg":"trace[438947556] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"419.335337ms","start":"2026-05-31T05:52:28.902507Z","end":"2026-05-31T05:52:29.321842Z","steps":["trace[438947556] 'process raft request' (duration: 419.192753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:29.321987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:28.902478Z","time spent":"419.45628ms","remote":"127.0.0.1:34700","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-05-31T05:52:29.322106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.531188ms","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-05-31T05:52:29.322141Z","caller":"traceutil/trace.go:171","msg":"trace[377038224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"323.621381ms","start":"2026-05-31T05:52:28.99851Z","end":"2026-05-31T05:52:29.322131Z","steps":["trace[377038224] 'agreement among raft nodes before linearized reading' (duration: 323.438996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:29.322174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:28.998489Z","time spent":"323.676762ms","remote":"127.0.0.1:34700","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-05-31T05:52:29.322371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.99245ms","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-05-31T05:52:29.322431Z","caller":"traceutil/trace.go:171","msg":"trace[1442056511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"163.069952ms","start":"2026-05-31T05:52:29.15935Z","end":"2026-05-31T05:52:29.32242Z","steps":["trace[1442056511] 'agreement among raft nodes before linearized reading' (duration: 162.925318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:29.322613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.22264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-31T05:52:29.32264Z","caller":"traceutil/trace.go:171","msg":"trace[151036465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:988; }","duration":"113.272821ms","start":"2026-05-31T05:52:29.209359Z","end":"2026-05-31T05:52:29.322632Z","steps":["trace[151036465] 'agreement among raft nodes before linearized reading' (duration: 113.195089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:29.322661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.244383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-31T05:52:29.322715Z","caller":"traceutil/trace.go:171","msg":"trace[1591471371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:988; }","duration":"105.352915ms","start":"2026-05-31T05:52:29.217349Z","end":"2026-05-31T05:52:29.322702Z","steps":["trace[1591471371] 'agreement among raft nodes before linearized reading' (duration: 105.15904ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:30.582709Z","caller":"traceutil/trace.go:171","msg":"trace[70260132] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"312.519916ms","start":"2026-05-31T05:52:30.270165Z","end":"2026-05-31T05:52:30.582685Z","steps":["trace[70260132] 'read index received' (duration: 312.243149ms)","trace[70260132] 'applied index is now lower than readState.Index' (duration: 275.617µs)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:30.582827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.64863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:30.582816Z","caller":"traceutil/trace.go:171","msg":"trace[1066453994] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"395.18674ms","start":"2026-05-31T05:52:30.187597Z","end":"2026-05-31T05:52:30.582784Z","steps":["trace[1066453994] 'process raft request' (duration: 394.933104ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:30.582857Z","caller":"traceutil/trace.go:171","msg":"trace[679139745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"312.720241ms","start":"2026-05-31T05:52:30.270127Z","end":"2026-05-31T05:52:30.582848Z","steps":["trace[679139745] 'agreement among raft nodes before linearized reading' (duration: 312.648719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:30.582896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:30.270114Z","time spent":"312.775453ms","remote":"127.0.0.1:34400","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-05-31T05:52:30.582983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:30.187572Z","time spent":"395.319434ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:32.058738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.050403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-31T05:52:32.058814Z","caller":"traceutil/trace.go:171","msg":"trace[1573708849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"722.169606ms","start":"2026-05-31T05:52:31.336627Z","end":"2026-05-31T05:52:32.058796Z","steps":["trace[1573708849] 'range keys from in-memory index tree' (duration: 721.894627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:32.058882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:31.336604Z","time spent":"722.258357ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-31T05:52:32.058891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064847467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:52:32.058986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.563928ms","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-05-31T05:52:32.058997Z","caller":"traceutil/trace.go:171","msg":"trace[278552973] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:994; }","duration":"1.064996081s","start":"2026-05-31T05:52:30.99397Z","end":"2026-05-31T05:52:32.058966Z","steps":["trace[278552973] 'count revisions from in-memory index tree' (duration: 1.064772255s)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:32.059059Z","caller":"traceutil/trace.go:171","msg":"trace[1018654236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"693.67514ms","start":"2026-05-31T05:52:31.365368Z","end":"2026-05-31T05:52:32.059043Z","steps":["trace[1018654236] 'range keys from in-memory index tree' (duration: 693.427823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:32.059084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:30.993951Z","time spent":"1.065111054s","remote":"127.0.0.1:51628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:52:32.059124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.153177ms","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-05-31T05:52:32.059226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.142261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-31T05:52:32.059237Z","caller":"traceutil/trace.go:171","msg":"trace[910454356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"693.291261ms","start":"2026-05-31T05:52:31.365922Z","end":"2026-05-31T05:52:32.059214Z","steps":["trace[910454356] 'range keys from in-memory index tree' (duration: 692.986774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:32.059099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:31.365352Z","time spent":"693.738792ms","remote":"127.0.0.1:34700","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-05-31T05:52:32.05932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.979187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-31T05:52:32.05935Z","caller":"traceutil/trace.go:171","msg":"trace[793616412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"694.014838ms","start":"2026-05-31T05:52:31.365327Z","end":"2026-05-31T05:52:32.059342Z","steps":["trace[793616412] 'range keys from in-memory index tree' (duration: 693.925196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:32.059378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:31.365323Z","time spent":"694.049789ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-31T05:52:32.059261Z","caller":"traceutil/trace.go:171","msg":"trace[33527530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:994; }","duration":"694.203783ms","start":"2026-05-31T05:52:31.365049Z","end":"2026-05-31T05:52:32.059252Z","steps":["trace[33527530] 'range keys from in-memory index tree' (duration: 694.067189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:32.059503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:31.365034Z","time spent":"694.45889ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-31T05:52:32.05931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:31.365916Z","time spent":"693.379783ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-31T05:52:33.008751Z","caller":"traceutil/trace.go:171","msg":"trace[782869934] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"421.099161ms","start":"2026-05-31T05:52:32.58762Z","end":"2026-05-31T05:52:33.008719Z","steps":["trace[782869934] 'process raft request' (duration: 420.973178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:33.008925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:32.587593Z","time spent":"421.247305ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:52:34.220564Z","caller":"traceutil/trace.go:171","msg":"trace[1484818754] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"107.766986ms","start":"2026-05-31T05:52:34.112774Z","end":"2026-05-31T05:52:34.220541Z","steps":["trace[1484818754] 'process raft request' (duration: 107.699374ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:34.220704Z","caller":"traceutil/trace.go:171","msg":"trace[165445627] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"107.260273ms","start":"2026-05-31T05:52:34.11343Z","end":"2026-05-31T05:52:34.220691Z","steps":["trace[165445627] 'process raft request' (duration: 107.081298ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:34.220589Z","caller":"traceutil/trace.go:171","msg":"trace[2079840933] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"107.809997ms","start":"2026-05-31T05:52:34.112732Z","end":"2026-05-31T05:52:34.220542Z","steps":["trace[2079840933] 'process raft request' (duration: 107.607622ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:43.817083Z","caller":"traceutil/trace.go:171","msg":"trace[730479353] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"167.329848ms","start":"2026-05-31T05:52:43.649728Z","end":"2026-05-31T05:52:43.817058Z","steps":["trace[730479353] 'process raft request' (duration: 167.166294ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:50.559712Z","caller":"traceutil/trace.go:171","msg":"trace[1235270792] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"137.637392ms","start":"2026-05-31T05:52:50.422057Z","end":"2026-05-31T05:52:50.559694Z","steps":["trace[1235270792] 'process raft request' (duration: 128.375954ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:50.559845Z","caller":"traceutil/trace.go:171","msg":"trace[1142485997] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"137.725576ms","start":"2026-05-31T05:52:50.422098Z","end":"2026-05-31T05:52:50.559824Z","steps":["trace[1142485997] 'process raft request' (duration: 137.576221ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:51.265027Z","caller":"traceutil/trace.go:171","msg":"trace[1061884810] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"172.560717ms","start":"2026-05-31T05:52:51.092442Z","end":"2026-05-31T05:52:51.265002Z","steps":["trace[1061884810] 'process raft request' (duration: 172.428923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:52.771022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400624933,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-31T05:52:52.800912Z","caller":"traceutil/trace.go:171","msg":"trace[834102580] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"530.427346ms","start":"2026-05-31T05:52:52.270461Z","end":"2026-05-31T05:52:52.800889Z","steps":["trace[834102580] 'read index received' (duration: 530.20528ms)","trace[834102580] 'applied index is now lower than readState.Index' (duration: 221.256µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:52.800967Z","caller":"traceutil/trace.go:171","msg":"trace[1819376819] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"560.314104ms","start":"2026-05-31T05:52:52.24062Z","end":"2026-05-31T05:52:52.800934Z","steps":["trace[1819376819] 'process raft request' (duration: 560.098068ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:52.801053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.57201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:52:52.801092Z","caller":"traceutil/trace.go:171","msg":"trace[1441988724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"530.639921ms","start":"2026-05-31T05:52:52.270443Z","end":"2026-05-31T05:52:52.801082Z","steps":["trace[1441988724] 'agreement among raft nodes before linearized reading' (duration: 530.555789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:52.801089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:52.240596Z","time spent":"560.418477ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:52:52.801122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:52.270421Z","time spent":"530.694833ms","remote":"127.0.0.1:34384","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-05-31T05:52:52.801399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.177801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-31T05:52:52.8014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.938873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-31T05:52:52.801421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.235155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:52:52.801428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.977354ms","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-05-31T05:52:52.801457Z","caller":"traceutil/trace.go:171","msg":"trace[688495929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"287.265414ms","start":"2026-05-31T05:52:52.514171Z","end":"2026-05-31T05:52:52.801436Z","steps":["trace[688495929] 'agreement among raft nodes before linearized reading' (duration: 287.154461ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:52.801478Z","caller":"traceutil/trace.go:171","msg":"trace[1425280167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"238.056186ms","start":"2026-05-31T05:52:52.563402Z","end":"2026-05-31T05:52:52.801459Z","steps":["trace[1425280167] 'agreement among raft nodes before linearized reading' (duration: 237.842611ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:52.80148Z","caller":"traceutil/trace.go:171","msg":"trace[1859303608] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1061; }","duration":"188.342057ms","start":"2026-05-31T05:52:52.613122Z","end":"2026-05-31T05:52:52.801464Z","steps":["trace[1859303608] 'agreement among raft nodes before linearized reading' (duration: 188.235015ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:52.801489Z","caller":"traceutil/trace.go:171","msg":"trace[1610277175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1061; }","duration":"238.067127ms","start":"2026-05-31T05:52:52.563408Z","end":"2026-05-31T05:52:52.801476Z","steps":["trace[1610277175] 'agreement among raft nodes before linearized reading' (duration: 237.882102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:52.801695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.339272ms","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-05-31T05:52:52.802257Z","caller":"traceutil/trace.go:171","msg":"trace[173641265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"515.899345ms","start":"2026-05-31T05:52:52.286345Z","end":"2026-05-31T05:52:52.802244Z","steps":["trace[173641265] 'agreement among raft nodes before linearized reading' (duration: 514.87325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:52.802309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:52.286306Z","time spent":"515.991507ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-31T05:52:55.060219Z","caller":"traceutil/trace.go:171","msg":"trace[2104743439] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"156.290546ms","start":"2026-05-31T05:52:54.903903Z","end":"2026-05-31T05:52:55.060194Z","steps":["trace[2104743439] 'process raft request' (duration: 97.640005ms)","trace[2104743439] 'compare' (duration: 58.447346ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:55.060666Z","caller":"traceutil/trace.go:171","msg":"trace[1696709984] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"156.709136ms","start":"2026-05-31T05:52:54.90394Z","end":"2026-05-31T05:52:55.060649Z","steps":["trace[1696709984] 'process raft request' (duration: 156.211804ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:55.060688Z","caller":"traceutil/trace.go:171","msg":"trace[900049877] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"156.124751ms","start":"2026-05-31T05:52:54.904536Z","end":"2026-05-31T05:52:55.060661Z","steps":["trace[900049877] 'process raft request' (duration: 156.023229ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:57.112104Z","caller":"traceutil/trace.go:171","msg":"trace[1292541375] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"175.034148ms","start":"2026-05-31T05:52:56.937039Z","end":"2026-05-31T05:52:57.112073Z","steps":["trace[1292541375] 'read index received' (duration: 174.810242ms)","trace[1292541375] 'applied index is now lower than readState.Index' (duration: 222.876µs)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:52:57.112239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.185512ms","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-05-31T05:52:57.112274Z","caller":"traceutil/trace.go:171","msg":"trace[1030386925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"175.264095ms","start":"2026-05-31T05:52:56.937003Z","end":"2026-05-31T05:52:57.112267Z","steps":["trace[1030386925] 'agreement among raft nodes before linearized reading' (duration: 175.11609ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:52:57.112127Z","caller":"traceutil/trace.go:171","msg":"trace[1475033938] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"204.25965ms","start":"2026-05-31T05:52:56.907833Z","end":"2026-05-31T05:52:57.112092Z","steps":["trace[1475033938] 'process raft request' (duration: 204.063986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:57.576576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.915813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:52:57.576839Z","caller":"traceutil/trace.go:171","msg":"trace[797485120] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"459.886431ms","start":"2026-05-31T05:52:57.116936Z","end":"2026-05-31T05:52:57.576822Z","steps":["trace[797485120] 'process raft request' (duration: 459.827779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:57.576908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:57.116931Z","time spent":"459.946502ms","remote":"127.0.0.1:34700","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-05-31T05:52:57.576979Z","caller":"traceutil/trace.go:171","msg":"trace[1809530005] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"460.303051ms","start":"2026-05-31T05:52:57.116547Z","end":"2026-05-31T05:52:57.57685Z","steps":["trace[1809530005] 'process raft request' (duration: 460.138057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:57.577068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:57.116525Z","time spent":"460.497596ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:52:57.577124Z","caller":"traceutil/trace.go:171","msg":"trace[339591258] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"461.215733ms","start":"2026-05-31T05:52:57.115872Z","end":"2026-05-31T05:52:57.577088Z","steps":["trace[339591258] 'process raft request' (duration: 351.339597ms)","trace[339591258] 'compare' (duration: 108.576845ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:57.577178Z","caller":"traceutil/trace.go:171","msg":"trace[564589674] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1117; }","duration":"306.21598ms","start":"2026-05-31T05:52:57.270952Z","end":"2026-05-31T05:52:57.577168Z","steps":["trace[564589674] 'read index received' (duration: 196.355614ms)","trace[564589674] 'applied index is now lower than readState.Index' (duration: 109.859156ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:52:57.577205Z","caller":"traceutil/trace.go:171","msg":"trace[1429304293] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"460.336342ms","start":"2026-05-31T05:52:57.116857Z","end":"2026-05-31T05:52:57.577193Z","steps":["trace[1429304293] 'process raft request' (duration: 459.879311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:57.577249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.303743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:52:57.57729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:57.11685Z","time spent":"460.382834ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:52:57.577302Z","caller":"traceutil/trace.go:171","msg":"trace[988763013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1079; }","duration":"306.396595ms","start":"2026-05-31T05:52:57.270897Z","end":"2026-05-31T05:52:57.577294Z","steps":["trace[988763013] 'agreement among raft nodes before linearized reading' (duration: 306.305052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:52:57.577329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:57.270875Z","time spent":"306.448627ms","remote":"127.0.0.1:34400","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-05-31T05:52:57.577332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:52:57.115841Z","time spent":"461.358577ms","remote":"127.0.0.1:34700","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-05-31T05:52:57.577591Z","caller":"traceutil/trace.go:171","msg":"trace[1439212226] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"283.702294ms","start":"2026-05-31T05:52:57.29387Z","end":"2026-05-31T05:52:57.577572Z","steps":["trace[1439212226] 'process raft request' (duration: 282.918954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:11.47959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.616563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:11.479761Z","caller":"traceutil/trace.go:171","msg":"trace[919375094] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1172; }","duration":"243.830518ms","start":"2026-05-31T05:53:11.235893Z","end":"2026-05-31T05:53:11.479723Z","steps":["trace[919375094] 'count revisions from in-memory index tree' (duration: 243.51728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:11.480217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.562576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:11.480292Z","caller":"traceutil/trace.go:171","msg":"trace[2077177231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1172; }","duration":"210.70525ms","start":"2026-05-31T05:53:11.269569Z","end":"2026-05-31T05:53:11.480274Z","steps":["trace[2077177231] 'range keys from in-memory index tree' (duration: 210.420533ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:11.847325Z","caller":"traceutil/trace.go:171","msg":"trace[2118072489] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"174.127555ms","start":"2026-05-31T05:53:11.673173Z","end":"2026-05-31T05:53:11.8473Z","steps":["trace[2118072489] 'process raft request' (duration: 173.993532ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:12.041829Z","caller":"traceutil/trace.go:171","msg":"trace[446101711] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"368.078801ms","start":"2026-05-31T05:53:11.67372Z","end":"2026-05-31T05:53:12.041799Z","steps":["trace[446101711] 'process raft request' (duration: 286.879094ms)","trace[446101711] 'compare' (duration: 80.643244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:12.041977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:11.673704Z","time spent":"368.197804ms","remote":"127.0.0.1:34700","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-05-31T05:53:12.042537Z","caller":"traceutil/trace.go:171","msg":"trace[413478881] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"367.24431ms","start":"2026-05-31T05:53:11.675275Z","end":"2026-05-31T05:53:12.04252Z","steps":["trace[413478881] 'process raft request' (duration: 367.185178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:12.042635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:11.675259Z","time spent":"367.330442ms","remote":"127.0.0.1:34700","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-05-31T05:53:12.042931Z","caller":"traceutil/trace.go:171","msg":"trace[1050910735] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"367.6591ms","start":"2026-05-31T05:53:11.67526Z","end":"2026-05-31T05:53:12.042919Z","steps":["trace[1050910735] 'process raft request' (duration: 366.137713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:12.042989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:11.675249Z","time spent":"367.709501ms","remote":"127.0.0.1:34700","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-05-31T05:53:13.770605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400625194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:53:14.271349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400625194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:53:14.771712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400625194,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:53:14.911251Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.717878863s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:53:14.911882Z","caller":"traceutil/trace.go:171","msg":"trace[1358579759] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"1.718339554s","start":"2026-05-31T05:53:13.193298Z","end":"2026-05-31T05:53:14.911638Z","steps":["trace[1358579759] 'process raft request' (duration: 1.718183141s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:14.912048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:13.193283Z","time spent":"1.718647251s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:53:14.942193Z","caller":"traceutil/trace.go:171","msg":"trace[318060502] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"1.26839171s","start":"2026-05-31T05:53:13.673785Z","end":"2026-05-31T05:53:14.942177Z","steps":["trace[318060502] 'process raft request' (duration: 1.268270387s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:14.942298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:13.673674Z","time spent":"1.268567414s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:53:14.94236Z","caller":"traceutil/trace.go:171","msg":"trace[917103397] linearizableReadLoop","detail":"{readStateIndex:1229; appliedIndex:1225; }","duration":"1.672255155s","start":"2026-05-31T05:53:13.270079Z","end":"2026-05-31T05:53:14.942335Z","steps":["trace[917103397] 'read index received' (duration: 1.641355081s)","trace[917103397] 'applied index is now lower than readState.Index' (duration: 30.892584ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:14.942466Z","caller":"traceutil/trace.go:171","msg":"trace[1995026495] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"292.604374ms","start":"2026-05-31T05:53:14.649849Z","end":"2026-05-31T05:53:14.942453Z","steps":["trace[1995026495] 'process raft request' (duration: 292.298037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:14.942555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.67246453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:14.942579Z","caller":"traceutil/trace.go:171","msg":"trace[236482930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1180; }","duration":"1.672530972s","start":"2026-05-31T05:53:13.27004Z","end":"2026-05-31T05:53:14.942571Z","steps":["trace[236482930] 'agreement among raft nodes before linearized reading' (duration: 1.67245792s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:14.942601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:13.270025Z","time spent":"1.672571283s","remote":"127.0.0.1:34384","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-05-31T05:53:14.942625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089812364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:53:14.942648Z","caller":"traceutil/trace.go:171","msg":"trace[1974632801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1180; }","duration":"1.089917127s","start":"2026-05-31T05:53:13.852725Z","end":"2026-05-31T05:53:14.942642Z","steps":["trace[1974632801] 'agreement among raft nodes before linearized reading' (duration: 1.089831015s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:14.942666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:13.852691Z","time spent":"1.089971888s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-31T05:53:14.942736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.797306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-31T05:53:14.942764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.938709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:14.942812Z","caller":"traceutil/trace.go:171","msg":"trace[1486976443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1180; }","duration":"895.895559ms","start":"2026-05-31T05:53:14.046897Z","end":"2026-05-31T05:53:14.942792Z","steps":["trace[1486976443] 'agreement among raft nodes before linearized reading' (duration: 895.675524ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:14.942823Z","caller":"traceutil/trace.go:171","msg":"trace[1588440924] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1180; }","duration":"633.066262ms","start":"2026-05-31T05:53:14.309741Z","end":"2026-05-31T05:53:14.942807Z","steps":["trace[1588440924] 'agreement among raft nodes before linearized reading' (duration: 632.948029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:14.942849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.183046ms","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-05-31T05:53:14.942802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-31T05:53:14.942872Z","caller":"traceutil/trace.go:171","msg":"trace[619727123] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1180; }","duration":"898.246847ms","start":"2026-05-31T05:53:14.044618Z","end":"2026-05-31T05:53:14.942865Z","steps":["trace[619727123] 'agreement among raft nodes before linearized reading' (duration: 898.158495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:14.942894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:14.044595Z","time spent":"898.296359ms","remote":"127.0.0.1:34700","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-05-31T05:53:14.942878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:14.309715Z","time spent":"633.141984ms","remote":"127.0.0.1:51670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:53:14.942854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:14.046891Z","time spent":"895.954631ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-31T05:53:14.942914Z","caller":"traceutil/trace.go:171","msg":"trace[529328075] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1180; }","duration":"500.567155ms","start":"2026-05-31T05:53:14.442319Z","end":"2026-05-31T05:53:14.942886Z","steps":["trace[529328075] 'agreement among raft nodes before linearized reading' (duration: 500.308188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:14.942975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:14.442293Z","time spent":"500.669248ms","remote":"127.0.0.1:60592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:53:14.942934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.429123ms","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-05-31T05:53:14.943087Z","caller":"traceutil/trace.go:171","msg":"trace[1468029149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1180; }","duration":"896.618659ms","start":"2026-05-31T05:53:14.046441Z","end":"2026-05-31T05:53:14.943059Z","steps":["trace[1468029149] 'agreement among raft nodes before linearized reading' (duration: 896.260669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:14.943162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:14.046432Z","time spent":"896.715991ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-31T05:53:19.120215Z","caller":"traceutil/trace.go:171","msg":"trace[1833885880] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"193.200953ms","start":"2026-05-31T05:53:18.926993Z","end":"2026-05-31T05:53:19.120194Z","steps":["trace[1833885880] 'process raft request' (duration: 193.032929ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:19.124292Z","caller":"traceutil/trace.go:171","msg":"trace[676061310] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"162.649796ms","start":"2026-05-31T05:53:18.9616Z","end":"2026-05-31T05:53:19.124249Z","steps":["trace[676061310] 'read index received' (duration: 158.724359ms)","trace[676061310] 'applied index is now lower than readState.Index' (duration: 3.924347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:19.12447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.861871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-31T05:53:19.124484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.566194ms","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-05-31T05:53:19.124529Z","caller":"traceutil/trace.go:171","msg":"trace[1986208368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1193; }","duration":"162.960494ms","start":"2026-05-31T05:53:18.961556Z","end":"2026-05-31T05:53:19.124517Z","steps":["trace[1986208368] 'agreement among raft nodes before linearized reading' (duration: 162.79229ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:19.124533Z","caller":"traceutil/trace.go:171","msg":"trace[770983962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1193; }","duration":"162.638805ms","start":"2026-05-31T05:53:18.961883Z","end":"2026-05-31T05:53:19.124522Z","steps":["trace[770983962] 'agreement among raft nodes before linearized reading' (duration: 162.499062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:19.124564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.277173ms","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-05-31T05:53:19.12463Z","caller":"traceutil/trace.go:171","msg":"trace[924988385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"159.385537ms","start":"2026-05-31T05:53:18.965233Z","end":"2026-05-31T05:53:19.124618Z","steps":["trace[924988385] 'agreement among raft nodes before linearized reading' (duration: 159.211662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:19.124676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.99694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:53:19.124726Z","caller":"traceutil/trace.go:171","msg":"trace[827094827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1193; }","duration":"161.073783ms","start":"2026-05-31T05:53:18.963641Z","end":"2026-05-31T05:53:19.124715Z","steps":["trace[827094827] 'agreement among raft nodes before linearized reading' (duration: 160.833016ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:19.124333Z","caller":"traceutil/trace.go:171","msg":"trace[1338241114] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"170.011978ms","start":"2026-05-31T05:53:18.954269Z","end":"2026-05-31T05:53:19.124281Z","steps":["trace[1338241114] 'process raft request' (duration: 169.890585ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:21.237005Z","caller":"traceutil/trace.go:171","msg":"trace[913995879] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"102.374474ms","start":"2026-05-31T05:53:21.134604Z","end":"2026-05-31T05:53:21.236979Z","steps":["trace[913995879] 'process raft request' (duration: 58.211704ms)","trace[913995879] 'compare' (duration: 44.039717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:30.68458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400625344,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:53:31.185103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567408400625344,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-31T05:53:31.57404Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.52720592s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:53:31.574489Z","caller":"traceutil/trace.go:171","msg":"trace[2133118075] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"1.527769755s","start":"2026-05-31T05:53:30.046693Z","end":"2026-05-31T05:53:31.574463Z","steps":["trace[2133118075] 'process raft request' (duration: 1.52756997s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.574648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:30.046675Z","time spent":"1.527890239s","remote":"127.0.0.1:34592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:53:31.754064Z","caller":"traceutil/trace.go:171","msg":"trace[2141814808] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1300; }","duration":"1.569697244s","start":"2026-05-31T05:53:30.184344Z","end":"2026-05-31T05:53:31.754041Z","steps":["trace[2141814808] 'read index received' (duration: 1.389920339s)","trace[2141814808] 'applied index is now lower than readState.Index' (duration: 179.775735ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:31.754105Z","caller":"traceutil/trace.go:171","msg":"trace[478425524] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"1.528854474s","start":"2026-05-31T05:53:30.225217Z","end":"2026-05-31T05:53:31.754072Z","steps":["trace[478425524] 'process raft request' (duration: 1.438196641s)","trace[478425524] 'compare' (duration: 90.230932ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:31.754192Z","caller":"traceutil/trace.go:171","msg":"trace[881826035] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"487.958329ms","start":"2026-05-31T05:53:31.266222Z","end":"2026-05-31T05:53:31.754181Z","steps":["trace[881826035] 'process raft request' (duration: 487.764683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.75426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:30.225152Z","time spent":"1.529024289s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:53:31.754303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:31.26621Z","time spent":"488.01028ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:53:31.754357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484042107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:31.754432Z","caller":"traceutil/trace.go:171","msg":"trace[160023569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"1.48415719s","start":"2026-05-31T05:53:30.270252Z","end":"2026-05-31T05:53:31.754409Z","steps":["trace[160023569] 'agreement among raft nodes before linearized reading' (duration: 1.483994036s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.754499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:30.270234Z","time spent":"1.484256704s","remote":"127.0.0.1:34400","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-05-31T05:53:31.754662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.497668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-31T05:53:31.754699Z","caller":"traceutil/trace.go:171","msg":"trace[2100720682] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1251; }","duration":"175.56188ms","start":"2026-05-31T05:53:31.579127Z","end":"2026-05-31T05:53:31.754689Z","steps":["trace[2100720682] 'agreement among raft nodes before linearized reading' (duration: 175.486428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.754702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.321251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:53:31.754796Z","caller":"traceutil/trace.go:171","msg":"trace[1653977036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"383.450095ms","start":"2026-05-31T05:53:31.371327Z","end":"2026-05-31T05:53:31.754777Z","steps":["trace[1653977036] 'agreement among raft nodes before linearized reading' (duration: 383.192078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.754818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.799224ms","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-05-31T05:53:31.754851Z","caller":"traceutil/trace.go:171","msg":"trace[817564634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"383.865226ms","start":"2026-05-31T05:53:31.370976Z","end":"2026-05-31T05:53:31.754841Z","steps":["trace[817564634] 'agreement among raft nodes before linearized reading' (duration: 383.716852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.754862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:31.371316Z","time spent":"383.525887ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-31T05:53:31.75489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:31.370956Z","time spent":"383.927798ms","remote":"127.0.0.1:34700","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-05-31T05:53:31.755073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.641226ms","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-05-31T05:53:31.755075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.045157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-31T05:53:31.755115Z","caller":"traceutil/trace.go:171","msg":"trace[1828683757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"377.703968ms","start":"2026-05-31T05:53:31.377401Z","end":"2026-05-31T05:53:31.755105Z","steps":["trace[1828683757] 'agreement among raft nodes before linearized reading' (duration: 377.593645ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:31.755132Z","caller":"traceutil/trace.go:171","msg":"trace[1969936069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"497.179031ms","start":"2026-05-31T05:53:31.257939Z","end":"2026-05-31T05:53:31.755118Z","steps":["trace[1969936069] 'agreement among raft nodes before linearized reading' (duration: 496.979895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.755151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:31.37739Z","time spent":"377.754429ms","remote":"127.0.0.1:34700","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-05-31T05:53:31.75517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:31.25792Z","time spent":"497.242913ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-31T05:53:31.755167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.257211ms","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-05-31T05:53:31.755218Z","caller":"traceutil/trace.go:171","msg":"trace[1633949124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1251; }","duration":"440.335063ms","start":"2026-05-31T05:53:31.314873Z","end":"2026-05-31T05:53:31.755208Z","steps":["trace[1633949124] 'agreement among raft nodes before linearized reading' (duration: 440.202579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.754893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570553557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:53:31.755254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:31.314858Z","time spent":"440.389065ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-31T05:53:31.755267Z","caller":"traceutil/trace.go:171","msg":"trace[1814147165] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1251; }","duration":"1.570962308s","start":"2026-05-31T05:53:30.18429Z","end":"2026-05-31T05:53:31.755253Z","steps":["trace[1814147165] 'agreement among raft nodes before linearized reading' (duration: 1.570573178s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.755299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:30.184271Z","time spent":"1.57101879s","remote":"127.0.0.1:41852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:53:31.945796Z","caller":"traceutil/trace.go:171","msg":"trace[1621005381] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"185.791089ms","start":"2026-05-31T05:53:31.759955Z","end":"2026-05-31T05:53:31.945746Z","steps":["trace[1621005381] 'process raft request' (duration: 185.681096ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:31.946024Z","caller":"traceutil/trace.go:171","msg":"trace[774197558] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1303; }","duration":"181.023429ms","start":"2026-05-31T05:53:31.764983Z","end":"2026-05-31T05:53:31.946006Z","steps":["trace[774197558] 'read index received' (duration: 22.539817ms)","trace[774197558] 'applied index is now lower than readState.Index' (duration: 158.482672ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:31.946097Z","caller":"traceutil/trace.go:171","msg":"trace[383859662] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"186.366975ms","start":"2026-05-31T05:53:31.759711Z","end":"2026-05-31T05:53:31.946078Z","steps":["trace[383859662] 'process raft request' (duration: 122.873945ms)","trace[383859662] 'compare' (duration: 62.918275ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:31.946219Z","caller":"traceutil/trace.go:171","msg":"trace[1943596007] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"186.17859ms","start":"2026-05-31T05:53:31.760014Z","end":"2026-05-31T05:53:31.946193Z","steps":["trace[1943596007] 'process raft request' (duration: 185.686727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.946342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.341359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:31.946426Z","caller":"traceutil/trace.go:171","msg":"trace[489754574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"181.42495ms","start":"2026-05-31T05:53:31.764957Z","end":"2026-05-31T05:53:31.946382Z","steps":["trace[489754574] 'agreement among raft nodes before linearized reading' (duration: 181.314117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:31.946452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.655852ms","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-05-31T05:53:31.946521Z","caller":"traceutil/trace.go:171","msg":"trace[396642122] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1257; }","duration":"133.781246ms","start":"2026-05-31T05:53:31.812719Z","end":"2026-05-31T05:53:31.9465Z","steps":["trace[396642122] 'agreement among raft nodes before linearized reading' (duration: 133.641212ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:31.946657Z","caller":"traceutil/trace.go:171","msg":"trace[351652952] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"186.451927ms","start":"2026-05-31T05:53:31.760191Z","end":"2026-05-31T05:53:31.946643Z","steps":["trace[351652952] 'process raft request' (duration: 185.705267ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:31.946677Z","caller":"traceutil/trace.go:171","msg":"trace[1558909604] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"185.955234ms","start":"2026-05-31T05:53:31.760704Z","end":"2026-05-31T05:53:31.946659Z","steps":["trace[1558909604] 'process raft request' (duration: 185.253385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:34.308953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.361652ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-31T05:53:34.309389Z","caller":"traceutil/trace.go:171","msg":"trace[1123030463] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"1.08626576s","start":"2026-05-31T05:53:33.223103Z","end":"2026-05-31T05:53:34.309369Z","steps":["trace[1123030463] 'read index received' (duration: 203.566338ms)","trace[1123030463] 'applied index is now lower than readState.Index' (duration: 882.698292ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:34.309596Z","caller":"traceutil/trace.go:171","msg":"trace[413670427] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"548.22653ms","start":"2026-05-31T05:53:33.76135Z","end":"2026-05-31T05:53:34.309577Z","steps":["trace[413670427] 'process raft request' (duration: 547.777868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:34.30973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:33.761332Z","time spent":"548.324473ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:53:34.446456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.807226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-31T05:53:34.446533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.370325ms","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-05-31T05:53:34.446583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223484279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:34.446585Z","caller":"traceutil/trace.go:171","msg":"trace[1872223169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"655.453067ms","start":"2026-05-31T05:53:33.791114Z","end":"2026-05-31T05:53:34.446567Z","steps":["trace[1872223169] 'agreement among raft nodes before linearized reading' (duration: 518.506435ms)","trace[1872223169] 'range keys from in-memory index tree' (duration: 136.832709ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:34.446617Z","caller":"traceutil/trace.go:171","msg":"trace[1971084272] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1259; }","duration":"1.223551061s","start":"2026-05-31T05:53:33.223051Z","end":"2026-05-31T05:53:34.446603Z","steps":["trace[1971084272] 'agreement among raft nodes before linearized reading' (duration: 1.086446095s)","trace[1971084272] 'count revisions from in-memory index tree' (duration: 137.039805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:34.446624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:33.791095Z","time spent":"655.520769ms","remote":"127.0.0.1:34700","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-05-31T05:53:34.446616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.926706ms","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-05-31T05:53:34.446699Z","caller":"traceutil/trace.go:171","msg":"trace[851129080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"496.0511ms","start":"2026-05-31T05:53:33.950625Z","end":"2026-05-31T05:53:34.446676Z","steps":["trace[851129080] 'agreement among raft nodes before linearized reading' (duration: 358.981545ms)","trace[851129080] 'range keys from in-memory index tree' (duration: 136.838239ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:34.44676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:33.95061Z","time spent":"496.136473ms","remote":"127.0.0.1:34700","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-05-31T05:53:34.446802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176897973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:34.44683Z","caller":"traceutil/trace.go:171","msg":"trace[824169952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"1.176948074s","start":"2026-05-31T05:53:33.269872Z","end":"2026-05-31T05:53:34.44682Z","steps":["trace[824169952] 'agreement among raft nodes before linearized reading' (duration: 1.039705464s)","trace[824169952] 'range keys from in-memory index tree' (duration: 137.198499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:34.446851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.283629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:53:34.44654Z","caller":"traceutil/trace.go:171","msg":"trace[1104625755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1259; }","duration":"494.947141ms","start":"2026-05-31T05:53:33.951572Z","end":"2026-05-31T05:53:34.446519Z","steps":["trace[1104625755] 'agreement among raft nodes before linearized reading' (duration: 358.03306ms)","trace[1104625755] 'range keys from in-memory index tree' (duration: 136.675554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:34.446865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:33.269858Z","time spent":"1.177001376s","remote":"127.0.0.1:34400","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-05-31T05:53:34.446883Z","caller":"traceutil/trace.go:171","msg":"trace[1582036415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1259; }","duration":"495.32522ms","start":"2026-05-31T05:53:33.951548Z","end":"2026-05-31T05:53:34.446873Z","steps":["trace[1582036415] 'agreement among raft nodes before linearized reading' (duration: 358.06944ms)","trace[1582036415] 'range keys from in-memory index tree' (duration: 137.175668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:34.44665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:33.22303Z","time spent":"1.223611323s","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:53:34.446911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:33.951542Z","time spent":"495.362991ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-31T05:53:34.446929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:33.951569Z","time spent":"495.331071ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-31T05:53:34.446773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.092091ms","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-05-31T05:53:34.447047Z","caller":"traceutil/trace.go:171","msg":"trace[328559730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1259; }","duration":"496.367908ms","start":"2026-05-31T05:53:33.950665Z","end":"2026-05-31T05:53:34.447033Z","steps":["trace[328559730] 'agreement among raft nodes before linearized reading' (duration: 358.910323ms)","trace[328559730] 'range keys from in-memory index tree' (duration: 137.130657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:34.447081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:33.950662Z","time spent":"496.41159ms","remote":"127.0.0.1:34700","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-05-31T05:53:34.672812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.521591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:53:34.673251Z","caller":"traceutil/trace.go:171","msg":"trace[1985369344] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"221.5332ms","start":"2026-05-31T05:53:34.451675Z","end":"2026-05-31T05:53:34.673208Z","steps":["trace[1985369344] 'process raft request' (duration: 96.535765ms)","trace[1985369344] 'compare' (duration: 124.402128ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:34.673391Z","caller":"traceutil/trace.go:171","msg":"trace[1407878996] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"221.465227ms","start":"2026-05-31T05:53:34.451903Z","end":"2026-05-31T05:53:34.673368Z","steps":["trace[1407878996] 'process raft request' (duration: 221.294903ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:34.673585Z","caller":"traceutil/trace.go:171","msg":"trace[612888991] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1313; }","duration":"216.967224ms","start":"2026-05-31T05:53:34.456571Z","end":"2026-05-31T05:53:34.673539Z","steps":["trace[612888991] 'read index received' (duration: 40.737016ms)","trace[612888991] 'applied index is now lower than readState.Index' (duration: 176.229068ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:34.673622Z","caller":"traceutil/trace.go:171","msg":"trace[1577118798] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"221.637112ms","start":"2026-05-31T05:53:34.451972Z","end":"2026-05-31T05:53:34.673609Z","steps":["trace[1577118798] 'process raft request' (duration: 221.338134ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:34.673604Z","caller":"traceutil/trace.go:171","msg":"trace[283845228] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"221.371435ms","start":"2026-05-31T05:53:34.452205Z","end":"2026-05-31T05:53:34.673577Z","steps":["trace[283845228] 'process raft request' (duration: 221.069687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:34.673824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.240002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:34.67387Z","caller":"traceutil/trace.go:171","msg":"trace[1962991280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"217.329975ms","start":"2026-05-31T05:53:34.45653Z","end":"2026-05-31T05:53:34.67386Z","steps":["trace[1962991280] 'agreement among raft nodes before linearized reading' (duration: 217.138879ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:34.678953Z","caller":"traceutil/trace.go:171","msg":"trace[444955060] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"123.816393ms","start":"2026-05-31T05:53:34.555121Z","end":"2026-05-31T05:53:34.678937Z","steps":["trace[444955060] 'process raft request' (duration: 123.69897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:35.050598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.750275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:53:35.050735Z","caller":"traceutil/trace.go:171","msg":"trace[321309482] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"350.211144ms","start":"2026-05-31T05:53:34.7005Z","end":"2026-05-31T05:53:35.050711Z","steps":["trace[321309482] 'process raft request' (duration: 132.177571ms)","trace[321309482] 'compare' (duration: 217.579111ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:35.050808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:34.700459Z","time spent":"350.317807ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:53:35.124633Z","caller":"traceutil/trace.go:171","msg":"trace[941392886] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"400.606126ms","start":"2026-05-31T05:53:34.723998Z","end":"2026-05-31T05:53:35.124604Z","steps":["trace[941392886] 'process raft request' (duration: 400.464832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:35.124802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:34.723978Z","time spent":"400.746799ms","remote":"127.0.0.1:34466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-31T05:53:35.229289Z","caller":"traceutil/trace.go:171","msg":"trace[2003150765] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"101.269265ms","start":"2026-05-31T05:53:35.127996Z","end":"2026-05-31T05:53:35.229265Z","steps":["trace[2003150765] 'process raft request' (duration: 36.358736ms)","trace[2003150765] 'compare' (duration: 64.772355ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:36.207542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.826238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:53:36.207653Z","caller":"traceutil/trace.go:171","msg":"trace[152738258] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"533.170376ms","start":"2026-05-31T05:53:35.674465Z","end":"2026-05-31T05:53:36.207635Z","steps":["trace[152738258] 'read index received' (duration: 40.681µs)","trace[152738258] 'applied index is now lower than readState.Index' (duration: 533.128325ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:36.20774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.27619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:36.207768Z","caller":"traceutil/trace.go:171","msg":"trace[1323011100] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1271; }","duration":"533.370122ms","start":"2026-05-31T05:53:35.674387Z","end":"2026-05-31T05:53:36.207757Z","steps":["trace[1323011100] 'agreement among raft nodes before linearized reading' (duration: 533.292409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:36.207802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:35.674367Z","time spent":"533.424383ms","remote":"127.0.0.1:51668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:53:36.207985Z","caller":"traceutil/trace.go:171","msg":"trace[319882280] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"901.315262ms","start":"2026-05-31T05:53:35.306636Z","end":"2026-05-31T05:53:36.207951Z","steps":["trace[319882280] 'process raft request' (duration: 298.998341ms)","trace[319882280] 'compare' (duration: 601.444697ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:36.208129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:35.306615Z","time spent":"901.435235ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:53:36.483755Z","caller":"traceutil/trace.go:171","msg":"trace[1085876925] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"168.918758ms","start":"2026-05-31T05:53:36.31481Z","end":"2026-05-31T05:53:36.483729Z","steps":["trace[1085876925] 'process raft request' (duration: 111.299988ms)","trace[1085876925] 'compare' (duration: 57.26369ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:36.781063Z","caller":"traceutil/trace.go:171","msg":"trace[1263558028] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"103.176296ms","start":"2026-05-31T05:53:36.677861Z","end":"2026-05-31T05:53:36.781038Z","steps":["trace[1263558028] 'read index received' (duration: 15.31121ms)","trace[1263558028] 'applied index is now lower than readState.Index' (duration: 87.864076ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:36.781223Z","caller":"traceutil/trace.go:171","msg":"trace[1172215697] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"276.989428ms","start":"2026-05-31T05:53:36.504218Z","end":"2026-05-31T05:53:36.781208Z","steps":["trace[1172215697] 'process raft request' (duration: 188.946927ms)","trace[1172215697] 'compare' (duration: 87.760953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:36.781576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.695ms","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-05-31T05:53:36.781595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.039913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-31T05:53:36.781621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.238588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-31T05:53:36.781642Z","caller":"traceutil/trace.go:171","msg":"trace[1877587364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"103.138136ms","start":"2026-05-31T05:53:36.678493Z","end":"2026-05-31T05:53:36.781631Z","steps":["trace[1877587364] 'agreement among raft nodes before linearized reading' (duration: 103.012702ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:36.781669Z","caller":"traceutil/trace.go:171","msg":"trace[1875307461] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1273; }","duration":"103.30911ms","start":"2026-05-31T05:53:36.678348Z","end":"2026-05-31T05:53:36.781657Z","steps":["trace[1875307461] 'agreement among raft nodes before linearized reading' (duration: 103.158986ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:36.781637Z","caller":"traceutil/trace.go:171","msg":"trace[548379786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"103.803563ms","start":"2026-05-31T05:53:36.67782Z","end":"2026-05-31T05:53:36.781623Z","steps":["trace[548379786] 'agreement among raft nodes before linearized reading' (duration: 103.635209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:36.78175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.196537ms","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-05-31T05:53:36.781794Z","caller":"traceutil/trace.go:171","msg":"trace[246886476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"103.254799ms","start":"2026-05-31T05:53:36.678527Z","end":"2026-05-31T05:53:36.781782Z","steps":["trace[246886476] 'agreement among raft nodes before linearized reading' (duration: 103.091904ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:36.886334Z","caller":"traceutil/trace.go:171","msg":"trace[1496933070] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"100.513394ms","start":"2026-05-31T05:53:36.785794Z","end":"2026-05-31T05:53:36.886307Z","steps":["trace[1496933070] 'process raft request' (duration: 98.851788ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:36.886371Z","caller":"traceutil/trace.go:171","msg":"trace[1164472333] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"100.271908ms","start":"2026-05-31T05:53:36.786058Z","end":"2026-05-31T05:53:36.88633Z","steps":["trace[1164472333] 'process raft request' (duration: 100.139713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:39.235726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.370892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:53:39.235827Z","caller":"traceutil/trace.go:171","msg":"trace[382840881] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"339.393748ms","start":"2026-05-31T05:53:38.896414Z","end":"2026-05-31T05:53:39.235808Z","steps":["trace[382840881] 'process raft request' (duration: 51.855081ms)","trace[382840881] 'compare' (duration: 287.051854ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:39.235885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:38.896396Z","time spent":"339.461319ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:53:42.905588Z","caller":"traceutil/trace.go:171","msg":"trace[1302466347] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"100.897464ms","start":"2026-05-31T05:53:42.804669Z","end":"2026-05-31T05:53:42.905566Z","steps":["trace[1302466347] 'read index received' (duration: 100.724209ms)","trace[1302466347] 'applied index is now lower than readState.Index' (duration: 172.225µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:42.905632Z","caller":"traceutil/trace.go:171","msg":"trace[1116872911] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"406.026033ms","start":"2026-05-31T05:53:42.499587Z","end":"2026-05-31T05:53:42.905613Z","steps":["trace[1116872911] 'process raft request' (duration: 405.773696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:42.905805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.12418ms","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-05-31T05:53:42.905968Z","caller":"traceutil/trace.go:171","msg":"trace[1734498066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"101.330015ms","start":"2026-05-31T05:53:42.804624Z","end":"2026-05-31T05:53:42.905954Z","steps":["trace[1734498066] 'agreement among raft nodes before linearized reading' (duration: 101.053268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:42.905795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:42.499537Z","time spent":"406.143037ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-31T05:53:44.528815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295954695s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:53:44.528921Z","caller":"traceutil/trace.go:171","msg":"trace[1169007765] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1380; }","duration":"1.616416944s","start":"2026-05-31T05:53:42.912485Z","end":"2026-05-31T05:53:44.528902Z","steps":["trace[1169007765] 'read index received' (duration: 320.298525ms)","trace[1169007765] 'applied index is now lower than readState.Index' (duration: 1.296116999s)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:44.528992Z","caller":"traceutil/trace.go:171","msg":"trace[970161475] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.617699819s","start":"2026-05-31T05:53:42.911281Z","end":"2026-05-31T05:53:44.528981Z","steps":["trace[970161475] 'process raft request' (duration: 321.493298ms)","trace[970161475] 'compare' (duration: 1.295524642s)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:44.529054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:42.91127Z","time spent":"1.617754082s","remote":"127.0.0.1:34700","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-05-31T05:53:44.52932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.616834585s","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-05-31T05:53:44.529351Z","caller":"traceutil/trace.go:171","msg":"trace[1405448197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1327; }","duration":"1.616884517s","start":"2026-05-31T05:53:42.912457Z","end":"2026-05-31T05:53:44.529341Z","steps":["trace[1405448197] 'agreement among raft nodes before linearized reading' (duration: 1.616766154s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.529379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:42.912449Z","time spent":"1.616924198s","remote":"127.0.0.1:34700","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-05-31T05:53:44.965527Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.732813613s","expected-duration":"1s"} {"level":"info","ts":"2026-05-31T05:53:44.966066Z","caller":"traceutil/trace.go:171","msg":"trace[362495009] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"2.053441307s","start":"2026-05-31T05:53:42.912601Z","end":"2026-05-31T05:53:44.966042Z","steps":["trace[362495009] 'process raft request' (duration: 2.053114838s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.966214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:42.912579Z","time spent":"2.05355702s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:53:44.966516Z","caller":"traceutil/trace.go:171","msg":"trace[366269890] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"2.053251411s","start":"2026-05-31T05:53:42.913214Z","end":"2026-05-31T05:53:44.966465Z","steps":["trace[366269890] 'process raft request' (duration: 2.052782509s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.966754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:42.913203Z","time spent":"2.053399285s","remote":"127.0.0.1:34700","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-05-31T05:53:44.974558Z","caller":"traceutil/trace.go:171","msg":"trace[145497799] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"441.176377ms","start":"2026-05-31T05:53:44.533334Z","end":"2026-05-31T05:53:44.97451Z","steps":["trace[145497799] 'process raft request' (duration: 437.524456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.974884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:44.533313Z","time spent":"441.465955ms","remote":"127.0.0.1:34700","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-05-31T05:53:44.975058Z","caller":"traceutil/trace.go:171","msg":"trace[241064507] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1381; }","duration":"446.0573ms","start":"2026-05-31T05:53:44.528946Z","end":"2026-05-31T05:53:44.975004Z","steps":["trace[241064507] 'read index received' (duration: 436.9294ms)","trace[241064507] 'applied index is now lower than readState.Index' (duration: 9.12676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:53:44.975119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.99748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:53:44.975158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.101432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-31T05:53:44.975223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.062269869s","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-05-31T05:53:44.975277Z","caller":"traceutil/trace.go:171","msg":"trace[1321296514] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1331; }","duration":"688.212675ms","start":"2026-05-31T05:53:44.28699Z","end":"2026-05-31T05:53:44.975202Z","steps":["trace[1321296514] 'agreement among raft nodes before linearized reading' (duration: 688.095012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.975297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.726404097s","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-05-31T05:53:44.975317Z","caller":"traceutil/trace.go:171","msg":"trace[1995869381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1331; }","duration":"1.726437328s","start":"2026-05-31T05:53:43.248872Z","end":"2026-05-31T05:53:44.975309Z","steps":["trace[1995869381] 'agreement among raft nodes before linearized reading' (duration: 1.726371116s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.975317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:44.286968Z","time spent":"688.339249ms","remote":"127.0.0.1:34670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:53:44.975348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:43.248846Z","time spent":"1.72649519s","remote":"127.0.0.1:34700","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-05-31T05:53:44.975362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.678072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-05-31T05:53:44.97538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.613633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:44.975399Z","caller":"traceutil/trace.go:171","msg":"trace[162238402] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1331; }","duration":"525.752634ms","start":"2026-05-31T05:53:44.449636Z","end":"2026-05-31T05:53:44.975388Z","steps":["trace[162238402] 'agreement among raft nodes before linearized reading' (duration: 525.558149ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:44.975469Z","caller":"traceutil/trace.go:171","msg":"trace[62969830] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1331; }","duration":"589.760846ms","start":"2026-05-31T05:53:44.385691Z","end":"2026-05-31T05:53:44.975452Z","steps":["trace[62969830] 'agreement among raft nodes before linearized reading' (duration: 589.598502ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:53:44.975508Z","caller":"traceutil/trace.go:171","msg":"trace[1874151511] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1331; }","duration":"2.062360701s","start":"2026-05-31T05:53:42.912879Z","end":"2026-05-31T05:53:44.97524Z","steps":["trace[1874151511] 'agreement among raft nodes before linearized reading' (duration: 2.062230567s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.975538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:44.38563Z","time spent":"589.88419ms","remote":"127.0.0.1:34546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:53:44.975548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.105821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-31T05:53:44.975585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.803686325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-31T05:53:44.975622Z","caller":"traceutil/trace.go:171","msg":"trace[1371986992] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1331; }","duration":"1.803765147s","start":"2026-05-31T05:53:43.171843Z","end":"2026-05-31T05:53:44.975608Z","steps":["trace[1371986992] 'agreement among raft nodes before linearized reading' (duration: 1.803681234s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.975654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:43.171816Z","time spent":"1.803832549s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-31T05:53:44.975174Z","caller":"traceutil/trace.go:171","msg":"trace[1629106445] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1331; }","duration":"622.063662ms","start":"2026-05-31T05:53:44.353086Z","end":"2026-05-31T05:53:44.97515Z","steps":["trace[1629106445] 'agreement among raft nodes before linearized reading' (duration: 621.957519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.975759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:44.353063Z","time spent":"622.685039ms","remote":"127.0.0.1:34682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-05-31T05:53:44.975839Z","caller":"traceutil/trace.go:171","msg":"trace[227177836] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1331; }","duration":"604.165763ms","start":"2026-05-31T05:53:44.37141Z","end":"2026-05-31T05:53:44.975575Z","steps":["trace[227177836] 'agreement among raft nodes before linearized reading' (duration: 604.093191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.975483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:44.44961Z","time spent":"525.865157ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-31T05:53:44.975552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:42.912853Z","time spent":"2.06269081s","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-31T05:53:44.975877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:44.371378Z","time spent":"604.488281ms","remote":"127.0.0.1:34820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-31T05:53:44.975152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.704461506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:53:44.976508Z","caller":"traceutil/trace.go:171","msg":"trace[1583051403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1331; }","duration":"1.705851483s","start":"2026-05-31T05:53:43.27062Z","end":"2026-05-31T05:53:44.976472Z","steps":["trace[1583051403] 'agreement among raft nodes before linearized reading' (duration: 1.704447555s)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:44.97654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:43.270599Z","time spent":"1.705932976s","remote":"127.0.0.1:34400","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-05-31T05:53:47.283477Z","caller":"traceutil/trace.go:171","msg":"trace[906747908] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"307.153895ms","start":"2026-05-31T05:53:46.976292Z","end":"2026-05-31T05:53:47.283446Z","steps":["trace[906747908] 'process raft request' (duration: 306.9447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:47.283661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:46.976276Z","time spent":"307.29734ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:53:47.300135Z","caller":"traceutil/trace.go:171","msg":"trace[131343709] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"323.258066ms","start":"2026-05-31T05:53:46.976848Z","end":"2026-05-31T05:53:47.300107Z","steps":["trace[131343709] 'process raft request' (duration: 323.082701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:47.30028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:46.976826Z","time spent":"323.378839ms","remote":"127.0.0.1:34700","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-05-31T05:53:47.300358Z","caller":"traceutil/trace.go:171","msg":"trace[119279251] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1394; }","duration":"316.85234ms","start":"2026-05-31T05:53:46.983491Z","end":"2026-05-31T05:53:47.300343Z","steps":["trace[119279251] 'read index received' (duration: 299.869875ms)","trace[119279251] 'applied index is now lower than readState.Index' (duration: 16.981365ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:53:47.300444Z","caller":"traceutil/trace.go:171","msg":"trace[1511675654] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"320.641934ms","start":"2026-05-31T05:53:46.979733Z","end":"2026-05-31T05:53:47.300375Z","steps":["trace[1511675654] 'process raft request' (duration: 320.335396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:47.300529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.047819ms","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-05-31T05:53:47.300529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.036396ms","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-05-31T05:53:47.300555Z","caller":"traceutil/trace.go:171","msg":"trace[169232171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1342; }","duration":"312.091549ms","start":"2026-05-31T05:53:46.988455Z","end":"2026-05-31T05:53:47.300546Z","steps":["trace[169232171] 'agreement among raft nodes before linearized reading' (duration: 311.997367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:47.300574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:46.988446Z","time spent":"312.122661ms","remote":"127.0.0.1:34700","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-05-31T05:53:47.300557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:46.979722Z","time spent":"320.774817ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-31T05:53:47.300572Z","caller":"traceutil/trace.go:171","msg":"trace[1682762377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1342; }","duration":"317.092417ms","start":"2026-05-31T05:53:46.983461Z","end":"2026-05-31T05:53:47.300553Z","steps":["trace[1682762377] 'agreement among raft nodes before linearized reading' (duration: 316.969673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:47.300636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:46.98345Z","time spent":"317.170179ms","remote":"127.0.0.1:34700","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-05-31T05:53:47.300676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.635346ms","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-05-31T05:53:47.300703Z","caller":"traceutil/trace.go:171","msg":"trace[1251599667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1342; }","duration":"308.673827ms","start":"2026-05-31T05:53:46.992022Z","end":"2026-05-31T05:53:47.300696Z","steps":["trace[1251599667] 'agreement among raft nodes before linearized reading' (duration: 308.598815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:53:47.300721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:53:46.992016Z","time spent":"308.701177ms","remote":"127.0.0.1:34700","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-05-31T05:53:55.223827Z","caller":"traceutil/trace.go:171","msg":"trace[1912551903] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"153.72054ms","start":"2026-05-31T05:53:55.070078Z","end":"2026-05-31T05:53:55.223799Z","steps":["trace[1912551903] 'process raft request' (duration: 153.440753ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:05.601587Z","caller":"traceutil/trace.go:171","msg":"trace[705687790] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"176.340042ms","start":"2026-05-31T05:54:05.425219Z","end":"2026-05-31T05:54:05.601559Z","steps":["trace[705687790] 'process raft request' (duration: 108.244396ms)","trace[705687790] 'compare' (duration: 67.862409ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:54:05.601887Z","caller":"traceutil/trace.go:171","msg":"trace[1392013071] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"176.589248ms","start":"2026-05-31T05:54:05.425278Z","end":"2026-05-31T05:54:05.601867Z","steps":["trace[1392013071] 'process raft request' (duration: 176.233898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:54:05.602057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.960436ms","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-05-31T05:54:05.602128Z","caller":"traceutil/trace.go:171","msg":"trace[874802510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1427; }","duration":"147.09553ms","start":"2026-05-31T05:54:05.454996Z","end":"2026-05-31T05:54:05.602092Z","steps":["trace[874802510] 'agreement among raft nodes before linearized reading' (duration: 146.887864ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:05.601801Z","caller":"traceutil/trace.go:171","msg":"trace[214098794] linearizableReadLoop","detail":"{readStateIndex:1486; appliedIndex:1484; }","duration":"146.732999ms","start":"2026-05-31T05:54:05.455038Z","end":"2026-05-31T05:54:05.601771Z","steps":["trace[214098794] 'read index received' (duration: 9.90413ms)","trace[214098794] 'applied index is now lower than readState.Index' (duration: 136.826159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-31T05:54:05.602521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.510138ms","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-05-31T05:54:05.602588Z","caller":"traceutil/trace.go:171","msg":"trace[691401983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1427; }","duration":"129.638042ms","start":"2026-05-31T05:54:05.472927Z","end":"2026-05-31T05:54:05.602565Z","steps":["trace[691401983] 'agreement among raft nodes before linearized reading' (duration: 129.363994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:54:16.969488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.924294ms","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-05-31T05:54:16.969586Z","caller":"traceutil/trace.go:171","msg":"trace[592856471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1477; }","duration":"110.098758ms","start":"2026-05-31T05:54:16.859462Z","end":"2026-05-31T05:54:16.969561Z","steps":["trace[592856471] 'range keys from in-memory index tree' (duration: 109.700029ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:17.573663Z","caller":"traceutil/trace.go:171","msg":"trace[2071473227] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"117.850029ms","start":"2026-05-31T05:54:17.455761Z","end":"2026-05-31T05:54:17.573611Z","steps":["trace[2071473227] 'process raft request' (duration: 117.559801ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:17.693229Z","caller":"traceutil/trace.go:171","msg":"trace[842171177] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"115.48038ms","start":"2026-05-31T05:54:17.577717Z","end":"2026-05-31T05:54:17.693197Z","steps":["trace[842171177] 'process raft request' (duration: 41.756164ms)","trace[842171177] 'compare' (duration: 73.593653ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:54:19.171402Z","caller":"traceutil/trace.go:171","msg":"trace[2050407046] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"115.926381ms","start":"2026-05-31T05:54:19.055452Z","end":"2026-05-31T05:54:19.171378Z","steps":["trace[2050407046] 'process raft request' (duration: 115.781198ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:21.068599Z","caller":"traceutil/trace.go:171","msg":"trace[1571277901] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"260.317159ms","start":"2026-05-31T05:54:20.808257Z","end":"2026-05-31T05:54:21.068574Z","steps":["trace[1571277901] 'process raft request' (duration: 260.168166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:54:21.311646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.205011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:54:21.311745Z","caller":"traceutil/trace.go:171","msg":"trace[1828589687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1496; }","duration":"138.360855ms","start":"2026-05-31T05:54:21.173359Z","end":"2026-05-31T05:54:21.31172Z","steps":["trace[1828589687] 'count revisions from in-memory index tree' (duration: 138.094828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:54:21.311836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.890896ms","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-05-31T05:54:21.312084Z","caller":"traceutil/trace.go:171","msg":"trace[1083137963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1496; }","duration":"136.246644ms","start":"2026-05-31T05:54:21.175814Z","end":"2026-05-31T05:54:21.31206Z","steps":["trace[1083137963] 'range keys from in-memory index tree' (duration: 135.685531ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:21.437204Z","caller":"traceutil/trace.go:171","msg":"trace[1135104978] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"120.606812ms","start":"2026-05-31T05:54:21.316566Z","end":"2026-05-31T05:54:21.437173Z","steps":["trace[1135104978] 'process raft request' (duration: 120.089589ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:21.924884Z","caller":"traceutil/trace.go:171","msg":"trace[43918765] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"176.221521ms","start":"2026-05-31T05:54:21.748635Z","end":"2026-05-31T05:54:21.924857Z","steps":["trace[43918765] 'process raft request' (duration: 103.477412ms)","trace[43918765] 'compare' (duration: 72.462432ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:54:21.925109Z","caller":"traceutil/trace.go:171","msg":"trace[435090686] linearizableReadLoop","detail":"{readStateIndex:1563; appliedIndex:1561; }","duration":"145.656662ms","start":"2026-05-31T05:54:21.779425Z","end":"2026-05-31T05:54:21.925082Z","steps":["trace[435090686] 'read index received' (duration: 72.589155ms)","trace[435090686] 'applied index is now lower than readState.Index' (duration: 73.065987ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:54:21.92525Z","caller":"traceutil/trace.go:171","msg":"trace[786085856] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"174.924198ms","start":"2026-05-31T05:54:21.750314Z","end":"2026-05-31T05:54:21.925238Z","steps":["trace[786085856] 'process raft request' (duration: 174.463227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:54:21.925582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.95782ms","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-05-31T05:54:21.925689Z","caller":"traceutil/trace.go:171","msg":"trace[1911883480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1501; }","duration":"146.119594ms","start":"2026-05-31T05:54:21.779544Z","end":"2026-05-31T05:54:21.925663Z","steps":["trace[1911883480] 'agreement among raft nodes before linearized reading' (duration: 145.840467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:54:21.92575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.306828ms","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-05-31T05:54:21.925819Z","caller":"traceutil/trace.go:171","msg":"trace[507520625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1501; }","duration":"146.431001ms","start":"2026-05-31T05:54:21.779372Z","end":"2026-05-31T05:54:21.925803Z","steps":["trace[507520625] 'agreement among raft nodes before linearized reading' (duration: 146.226746ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:22.192596Z","caller":"traceutil/trace.go:171","msg":"trace[2012587076] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"261.965136ms","start":"2026-05-31T05:54:21.930377Z","end":"2026-05-31T05:54:22.192342Z","steps":["trace[2012587076] 'process raft request' (duration: 261.73194ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:22.202143Z","caller":"traceutil/trace.go:171","msg":"trace[719450609] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"271.6734ms","start":"2026-05-31T05:54:21.93044Z","end":"2026-05-31T05:54:22.202113Z","steps":["trace[719450609] 'process raft request' (duration: 271.479145ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:30.181001Z","caller":"traceutil/trace.go:171","msg":"trace[1981970497] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"145.491347ms","start":"2026-05-31T05:54:30.035428Z","end":"2026-05-31T05:54:30.180919Z","steps":["trace[1981970497] 'process raft request' (duration: 145.234621ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:54:30.181212Z","caller":"traceutil/trace.go:171","msg":"trace[63087975] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"148.246037ms","start":"2026-05-31T05:54:30.032936Z","end":"2026-05-31T05:54:30.181182Z","steps":["trace[63087975] 'process raft request' (duration: 89.90828ms)","trace[63087975] 'compare' (duration: 57.64884ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:54:35.395567Z","caller":"traceutil/trace.go:171","msg":"trace[711893494] linearizableReadLoop","detail":"{readStateIndex:1623; appliedIndex:1622; }","duration":"122.793896ms","start":"2026-05-31T05:54:35.272753Z","end":"2026-05-31T05:54:35.395547Z","steps":["trace[711893494] 'read index received' (duration: 122.580891ms)","trace[711893494] 'applied index is now lower than readState.Index' (duration: 212.335µs)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:54:35.395678Z","caller":"traceutil/trace.go:171","msg":"trace[159737904] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"158.962896ms","start":"2026-05-31T05:54:35.236676Z","end":"2026-05-31T05:54:35.395639Z","steps":["trace[159737904] 'process raft request' (duration: 158.7181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:54:35.395756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.993491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-31T05:54:35.395801Z","caller":"traceutil/trace.go:171","msg":"trace[2002111193] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1558; }","duration":"123.069413ms","start":"2026-05-31T05:54:35.272717Z","end":"2026-05-31T05:54:35.395787Z","steps":["trace[2002111193] 'agreement among raft nodes before linearized reading' (duration: 122.912329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:18.162753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.478402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-31T05:55:18.162975Z","caller":"traceutil/trace.go:171","msg":"trace[1475408833] linearizableReadLoop","detail":"{readStateIndex:1829; appliedIndex:1827; }","duration":"107.969035ms","start":"2026-05-31T05:55:18.054989Z","end":"2026-05-31T05:55:18.162958Z","steps":["trace[1475408833] 'read index received' (duration: 2.130894ms)","trace[1475408833] 'applied index is now lower than readState.Index' (duration: 105.837101ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:55:18.162987Z","caller":"traceutil/trace.go:171","msg":"trace[679160410] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"150.644898ms","start":"2026-05-31T05:55:18.012317Z","end":"2026-05-31T05:55:18.162961Z","steps":["trace[679160410] 'process raft request' (duration: 44.865198ms)","trace[679160410] 'compare' (duration: 105.285897ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:55:18.163072Z","caller":"traceutil/trace.go:171","msg":"trace[1316680951] transaction","detail":"{read_only:false; response_revision:1755; number_of_response:1; }","duration":"137.911177ms","start":"2026-05-31T05:55:18.025151Z","end":"2026-05-31T05:55:18.163062Z","steps":["trace[1316680951] 'process raft request' (duration: 137.709972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:55:18.163326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.306564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:55:18.163394Z","caller":"traceutil/trace.go:171","msg":"trace[1674710015] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1755; }","duration":"108.435257ms","start":"2026-05-31T05:55:18.054942Z","end":"2026-05-31T05:55:18.163378Z","steps":["trace[1674710015] 'agreement among raft nodes before linearized reading' (duration: 108.232682ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:55:20.820471Z","caller":"traceutil/trace.go:171","msg":"trace[837861453] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"135.775664ms","start":"2026-05-31T05:55:20.684669Z","end":"2026-05-31T05:55:20.820445Z","steps":["trace[837861453] 'process raft request' (duration: 135.366953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:56:03.39507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.382048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-31T05:56:03.39517Z","caller":"traceutil/trace.go:171","msg":"trace[1265178856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1964; }","duration":"123.537674ms","start":"2026-05-31T05:56:03.271614Z","end":"2026-05-31T05:56:03.395151Z","steps":["trace[1265178856] 'range keys from in-memory index tree' (duration: 123.266446ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:56:29.06193Z","caller":"traceutil/trace.go:171","msg":"trace[825856913] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"156.337775ms","start":"2026-05-31T05:56:28.905568Z","end":"2026-05-31T05:56:29.061906Z","steps":["trace[825856913] 'process raft request' (duration: 153.232511ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:57:06.743772Z","caller":"traceutil/trace.go:171","msg":"trace[705622003] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"115.249278ms","start":"2026-05-31T05:57:06.628503Z","end":"2026-05-31T05:57:06.743752Z","steps":["trace[705622003] 'process raft request' (duration: 112.58692ms)"],"step_count":1} {"level":"info","ts":"2026-05-31T05:57:33.012887Z","caller":"traceutil/trace.go:171","msg":"trace[2121875368] transaction","detail":"{read_only:false; response_revision:2394; number_of_response:1; }","duration":"172.952697ms","start":"2026-05-31T05:57:32.839894Z","end":"2026-05-31T05:57:33.012847Z","steps":["trace[2121875368] 'process raft request' (duration: 172.66209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:57:37.290881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.159006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-31T05:57:37.290989Z","caller":"traceutil/trace.go:171","msg":"trace[56314984] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2418; }","duration":"140.512464ms","start":"2026-05-31T05:57:37.150452Z","end":"2026-05-31T05:57:37.290964Z","steps":["trace[56314984] 'count revisions from in-memory index tree' (duration: 140.024313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:57:41.326245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.169764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-31T05:57:41.326363Z","caller":"traceutil/trace.go:171","msg":"trace[1057142492] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2435; }","duration":"474.365728ms","start":"2026-05-31T05:57:40.851969Z","end":"2026-05-31T05:57:41.326334Z","steps":["trace[1057142492] 'range keys from in-memory index tree' (duration: 473.961999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:57:41.326457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-31T05:57:40.851939Z","time spent":"474.506353ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-31T05:57:41.326953Z","caller":"traceutil/trace.go:171","msg":"trace[1245499238] linearizableReadLoop","detail":"{readStateIndex:2539; appliedIndex:2538; }","duration":"191.608479ms","start":"2026-05-31T05:57:41.135319Z","end":"2026-05-31T05:57:41.326927Z","steps":["trace[1245499238] 'read index received' (duration: 188.953265ms)","trace[1245499238] 'applied index is now lower than readState.Index' (duration: 2.653854ms)"],"step_count":2} {"level":"info","ts":"2026-05-31T05:57:41.327087Z","caller":"traceutil/trace.go:171","msg":"trace[139515701] transaction","detail":"{read_only:false; response_revision:2436; number_of_response:1; }","duration":"276.645302ms","start":"2026-05-31T05:57:41.050427Z","end":"2026-05-31T05:57:41.327072Z","steps":["trace[139515701] 'process raft request' (duration: 273.802494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-31T05:57:41.327466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.117241ms","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-05-31T05:57:41.327549Z","caller":"traceutil/trace.go:171","msg":"trace[507386576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2436; }","duration":"192.257044ms","start":"2026-05-31T05:57:41.135274Z","end":"2026-05-31T05:57:41.327531Z","steps":["trace[507386576] 'agreement among raft nodes before linearized reading' (duration: 192.019409ms)"],"step_count":1}