{"level":"warn","ts":"2026-04-23T10:30:27.574242Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T10:30:27.574367Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-23T10:30:27.574439Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T10:30:27.574456Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-23T10:30:27.574481Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T10:30:27.575375Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-04-23T10:30:27.575461Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-23T10:30:27.58007Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.386778ms"} {"level":"info","ts":"2026-04-23T10:30:27.586898Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-04-23T10:30:27.587023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T10:30:27.587056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-04-23T10:30:27.587069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T10:30:27.587123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-04-23T10:30:27.587195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-04-23T10:30:27.594269Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T10:30:27.595921Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T10:30:27.59739Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-23T10:30:27.600014Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T10:30:27.600312Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T10:30:27.600423Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T10:30:27.600484Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T10:30:27.600553Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T10:30:27.60137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-04-23T10:30:27.601608Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-04-23T10:30:27.605899Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T10:30:27.606031Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-23T10:30:27.606187Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-04-23T10:30:27.607283Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T10:30:27.607237Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T10:30:27.687765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T10:30:27.687838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T10:30:27.687871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-04-23T10:30:27.687893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-04-23T10:30:27.687905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-23T10:30:27.687921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-04-23T10:30:27.687942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-04-23T10:30:27.689802Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T10:30:27.691407Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T10:30:27.691455Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T10:30:27.69183Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T10:30:27.691962Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T10:30:27.691995Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T10:30:27.695406Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T10:30:27.695507Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T10:30:27.696796Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T10:30:27.696866Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T10:30:27.698346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-04-23T10:30:31.690558Z","caller":"traceutil/trace.go:171","msg":"trace[1860424896] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"192.64053ms","start":"2026-04-23T10:30:31.497885Z","end":"2026-04-23T10:30:31.690525Z","steps":["trace[1860424896] 'process raft request' (duration: 192.477547ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:31.693796Z","caller":"traceutil/trace.go:171","msg":"trace[1458575324] linearizableReadLoop","detail":"{readStateIndex:209; appliedIndex:207; }","duration":"119.882913ms","start":"2026-04-23T10:30:31.573891Z","end":"2026-04-23T10:30:31.693774Z","steps":["trace[1458575324] 'read index received' (duration: 116.511698ms)","trace[1458575324] 'applied index is now lower than readState.Index' (duration: 3.370615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:30:31.693989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.003005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:30:31.694036Z","caller":"traceutil/trace.go:171","msg":"trace[1060272144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:205; }","duration":"120.192389ms","start":"2026-04-23T10:30:31.573833Z","end":"2026-04-23T10:30:31.694026Z","steps":["trace[1060272144] 'agreement among raft nodes before linearized reading' (duration: 120.022616ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:31.694008Z","caller":"traceutil/trace.go:171","msg":"trace[900415792] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"201.968129ms","start":"2026-04-23T10:30:31.491989Z","end":"2026-04-23T10:30:31.693958Z","steps":["trace[900415792] 'process raft request' (duration: 201.668842ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:33.631121Z","caller":"traceutil/trace.go:171","msg":"trace[1183023064] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"237.985715ms","start":"2026-04-23T10:30:33.3931Z","end":"2026-04-23T10:30:33.631086Z","steps":["trace[1183023064] 'process raft request' (duration: 237.869853ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:33.687849Z","caller":"traceutil/trace.go:171","msg":"trace[1195245451] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"294.685914ms","start":"2026-04-23T10:30:33.393125Z","end":"2026-04-23T10:30:33.687811Z","steps":["trace[1195245451] 'process raft request' (duration: 294.483509ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:33.688001Z","caller":"traceutil/trace.go:171","msg":"trace[311505067] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"280.979168ms","start":"2026-04-23T10:30:33.406995Z","end":"2026-04-23T10:30:33.687974Z","steps":["trace[311505067] 'process raft request' (duration: 280.749643ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:33.688088Z","caller":"traceutil/trace.go:171","msg":"trace[902260248] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"280.667431ms","start":"2026-04-23T10:30:33.407365Z","end":"2026-04-23T10:30:33.688033Z","steps":["trace[902260248] 'process raft request' (duration: 280.520778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:30:33.688147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.803378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-23T10:30:33.688002Z","caller":"traceutil/trace.go:171","msg":"trace[318493347] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:286; }","duration":"286.664455ms","start":"2026-04-23T10:30:33.401309Z","end":"2026-04-23T10:30:33.687974Z","steps":["trace[318493347] 'read index received' (duration: 229.73959ms)","trace[318493347] 'applied index is now lower than readState.Index' (duration: 56.923435ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:30:33.688199Z","caller":"traceutil/trace.go:171","msg":"trace[770209749] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:284; }","duration":"286.92003ms","start":"2026-04-23T10:30:33.401265Z","end":"2026-04-23T10:30:33.688185Z","steps":["trace[770209749] 'agreement among raft nodes before linearized reading' (duration: 286.787777ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:33.688291Z","caller":"traceutil/trace.go:171","msg":"trace[998355826] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"281.177992ms","start":"2026-04-23T10:30:33.407098Z","end":"2026-04-23T10:30:33.688276Z","steps":["trace[998355826] 'process raft request' (duration: 280.686341ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:33.688315Z","caller":"traceutil/trace.go:171","msg":"trace[1821973846] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"280.734052ms","start":"2026-04-23T10:30:33.407481Z","end":"2026-04-23T10:30:33.688215Z","steps":["trace[1821973846] 'process raft request' (duration: 280.426155ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:33.688325Z","caller":"traceutil/trace.go:171","msg":"trace[1919730138] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"281.173472ms","start":"2026-04-23T10:30:33.40714Z","end":"2026-04-23T10:30:33.688313Z","steps":["trace[1919730138] 'process raft request' (duration: 280.720262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:30:33.688335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.216003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:30:33.688394Z","caller":"traceutil/trace.go:171","msg":"trace[829742882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:284; }","duration":"117.321635ms","start":"2026-04-23T10:30:33.571049Z","end":"2026-04-23T10:30:33.688371Z","steps":["trace[829742882] 'agreement among raft nodes before linearized reading' (duration: 117.206053ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:33.688437Z","caller":"traceutil/trace.go:171","msg":"trace[1755057223] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"251.477607ms","start":"2026-04-23T10:30:33.436949Z","end":"2026-04-23T10:30:33.688427Z","steps":["trace[1755057223] 'process raft request' (duration: 250.977116ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:40.03594Z","caller":"traceutil/trace.go:171","msg":"trace[2013831246] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"227.42331ms","start":"2026-04-23T10:30:39.808479Z","end":"2026-04-23T10:30:40.035902Z","steps":["trace[2013831246] 'read index received' (duration: 227.228935ms)","trace[2013831246] 'applied index is now lower than readState.Index' (duration: 190.714µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:30:40.036026Z","caller":"traceutil/trace.go:171","msg":"trace[2072550483] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"470.614739ms","start":"2026-04-23T10:30:39.565375Z","end":"2026-04-23T10:30:40.035989Z","steps":["trace[2072550483] 'process raft request' (duration: 470.391225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:30:40.03611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.618803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T10:30:40.036165Z","caller":"traceutil/trace.go:171","msg":"trace[745180470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:387; }","duration":"227.724416ms","start":"2026-04-23T10:30:39.808424Z","end":"2026-04-23T10:30:40.036148Z","steps":["trace[745180470] 'agreement among raft nodes before linearized reading' (duration: 227.623474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:30:40.036254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.795718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T10:30:40.036343Z","caller":"traceutil/trace.go:171","msg":"trace[1748570531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:387; }","duration":"215.915681ms","start":"2026-04-23T10:30:39.820409Z","end":"2026-04-23T10:30:40.036325Z","steps":["trace[1748570531] 'agreement among raft nodes before linearized reading' (duration: 215.772358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:30:40.036488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:30:39.565345Z","time spent":"470.749603ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:30:40.036568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.072594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T10:30:40.03665Z","caller":"traceutil/trace.go:171","msg":"trace[1036678301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:387; }","duration":"216.185127ms","start":"2026-04-23T10:30:39.820455Z","end":"2026-04-23T10:30:40.03664Z","steps":["trace[1036678301] 'agreement among raft nodes before linearized reading' (duration: 216.075504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:30:40.542242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367202964670058,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T10:30:40.651797Z","caller":"traceutil/trace.go:171","msg":"trace[1493642889] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"609.899895ms","start":"2026-04-23T10:30:40.041865Z","end":"2026-04-23T10:30:40.651765Z","steps":["trace[1493642889] 'process raft request' (duration: 609.715561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:30:40.652972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:30:40.041827Z","time spent":"610.104791ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:30:41.759521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.893162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:30:41.759762Z","caller":"traceutil/trace.go:171","msg":"trace[608624314] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:402; }","duration":"1.717596s","start":"2026-04-23T10:30:40.04214Z","end":"2026-04-23T10:30:41.759736Z","steps":["trace[608624314] 'read index received' (duration: 609.140569ms)","trace[608624314] 'applied index is now lower than readState.Index' (duration: 1.108454321s)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:30:41.759892Z","caller":"traceutil/trace.go:171","msg":"trace[924226143] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"1.717929387s","start":"2026-04-23T10:30:40.041944Z","end":"2026-04-23T10:30:41.759873Z","steps":["trace[924226143] 'process raft request' (duration: 1.122371171s)","trace[924226143] 'compare' (duration: 594.727248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:30:41.759976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:30:40.041906Z","time spent":"1.7180282s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:30:41.76008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717907187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"warn","ts":"2026-04-23T10:30:41.760171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413893286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:30:41.760176Z","caller":"traceutil/trace.go:171","msg":"trace[1460953531] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:390; }","duration":"1.718058161s","start":"2026-04-23T10:30:40.042094Z","end":"2026-04-23T10:30:41.760152Z","steps":["trace[1460953531] 'agreement among raft nodes before linearized reading' (duration: 1.717816295s)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:41.760209Z","caller":"traceutil/trace.go:171","msg":"trace[331494254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"1.413952638s","start":"2026-04-23T10:30:40.346245Z","end":"2026-04-23T10:30:41.760198Z","steps":["trace[331494254] 'agreement among raft nodes before linearized reading' (duration: 1.413895036s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:30:41.760241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:30:40.346226Z","time spent":"1.414009808s","remote":"127.0.0.1:35864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T10:30:41.760248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:30:40.042077Z","time spent":"1.718161292s","remote":"127.0.0.1:36038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7326,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-04-23T10:30:41.760403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.28372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-23T10:30:41.760475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.470581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T10:30:41.76057Z","caller":"traceutil/trace.go:171","msg":"trace[1710450119] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:390; }","duration":"633.494995ms","start":"2026-04-23T10:30:41.127025Z","end":"2026-04-23T10:30:41.76052Z","steps":["trace[1710450119] 'agreement among raft nodes before linearized reading' (duration: 633.184158ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:41.760579Z","caller":"traceutil/trace.go:171","msg":"trace[1228599584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"918.621184ms","start":"2026-04-23T10:30:40.841934Z","end":"2026-04-23T10:30:41.760555Z","steps":["trace[1228599584] 'agreement among raft nodes before linearized reading' (duration: 918.43849ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:30:41.760596Z","caller":"traceutil/trace.go:171","msg":"trace[1679296371] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"1.717725493s","start":"2026-04-23T10:30:40.042805Z","end":"2026-04-23T10:30:41.76053Z","steps":["trace[1679296371] 'process raft request' (duration: 1.716823653s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:30:41.760641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:30:40.84191Z","time spent":"918.722596ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T10:30:41.760649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:30:41.127003Z","time spent":"633.634367ms","remote":"127.0.0.1:35894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-23T10:30:41.760738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:30:40.042783Z","time spent":"1.717854906s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:31:01.271874Z","caller":"traceutil/trace.go:171","msg":"trace[198068371] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"108.138949ms","start":"2026-04-23T10:31:01.163702Z","end":"2026-04-23T10:31:01.271841Z","steps":["trace[198068371] 'process raft request' (duration: 107.824932ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:02.251955Z","caller":"traceutil/trace.go:171","msg":"trace[395411139] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"561.79353ms","start":"2026-04-23T10:31:01.690134Z","end":"2026-04-23T10:31:02.251928Z","steps":["trace[395411139] 'process raft request' (duration: 561.645746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.25245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.690105Z","time spent":"561.930743ms","remote":"127.0.0.1:35902","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-04-23T10:31:02.260272Z","caller":"traceutil/trace.go:171","msg":"trace[1007281090] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"565.203486ms","start":"2026-04-23T10:31:01.69503Z","end":"2026-04-23T10:31:02.260233Z","steps":["trace[1007281090] 'process raft request' (duration: 564.90135ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:02.260582Z","caller":"traceutil/trace.go:171","msg":"trace[1236677697] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"530.901298ms","start":"2026-04-23T10:31:01.729671Z","end":"2026-04-23T10:31:02.260572Z","steps":["trace[1236677697] 'process raft request' (duration: 530.736324ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:02.260548Z","caller":"traceutil/trace.go:171","msg":"trace[1819963507] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"534.737064ms","start":"2026-04-23T10:31:01.725761Z","end":"2026-04-23T10:31:02.260498Z","steps":["trace[1819963507] 'process raft request' (duration: 534.55462ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:02.260283Z","caller":"traceutil/trace.go:171","msg":"trace[791609763] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"561.039063ms","start":"2026-04-23T10:31:01.699215Z","end":"2026-04-23T10:31:02.260254Z","steps":["trace[791609763] 'process raft request' (duration: 560.870139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.260672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.729651Z","time spent":"530.96773ms","remote":"127.0.0.1:35902","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-04-23T10:31:02.26073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.725731Z","time spent":"534.913938ms","remote":"127.0.0.1:35902","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-04-23T10:31:02.260372Z","caller":"traceutil/trace.go:171","msg":"trace[1863623947] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"554.64165ms","start":"2026-04-23T10:31:01.705704Z","end":"2026-04-23T10:31:02.260345Z","steps":["trace[1863623947] 'process raft request' (duration: 554.457326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.260824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.699206Z","time spent":"561.518263ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T10:31:02.260923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.705698Z","time spent":"555.170261ms","remote":"127.0.0.1:35902","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-04-23T10:31:02.26036Z","caller":"traceutil/trace.go:171","msg":"trace[1544147414] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"554.716761ms","start":"2026-04-23T10:31:01.705609Z","end":"2026-04-23T10:31:02.260325Z","steps":["trace[1544147414] 'process raft request' (duration: 554.520517ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:02.26037Z","caller":"traceutil/trace.go:171","msg":"trace[1200506761] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"552.976502ms","start":"2026-04-23T10:31:01.707374Z","end":"2026-04-23T10:31:02.26035Z","steps":["trace[1200506761] 'process raft request' (duration: 552.824209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.261105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.705589Z","time spent":"555.488558ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T10:31:02.260426Z","caller":"traceutil/trace.go:171","msg":"trace[405426481] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"545.048394ms","start":"2026-04-23T10:31:01.715366Z","end":"2026-04-23T10:31:02.260415Z","steps":["trace[405426481] 'process raft request' (duration: 544.900321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.261365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.715354Z","time spent":"545.987295ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T10:31:02.261227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.707366Z","time spent":"553.8247ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T10:31:02.261677Z","caller":"traceutil/trace.go:171","msg":"trace[267177365] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"515.389451ms","start":"2026-04-23T10:31:01.746278Z","end":"2026-04-23T10:31:02.261667Z","steps":["trace[267177365] 'process raft request' (duration: 515.014452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.261735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.746249Z","time spent":"515.468333ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T10:31:02.261872Z","caller":"traceutil/trace.go:171","msg":"trace[603514730] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"492.07704ms","start":"2026-04-23T10:31:01.769782Z","end":"2026-04-23T10:31:02.261859Z","steps":["trace[603514730] 'process raft request' (duration: 491.894696ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:02.261909Z","caller":"traceutil/trace.go:171","msg":"trace[241127798] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:525; }","duration":"394.865535ms","start":"2026-04-23T10:31:01.867031Z","end":"2026-04-23T10:31:02.261896Z","steps":["trace[241127798] 'read index received' (duration: 384.770479ms)","trace[241127798] 'applied index is now lower than readState.Index' (duration: 10.094146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:31:02.261107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.69501Z","time spent":"565.513253ms","remote":"127.0.0.1:35902","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-04-23T10:31:02.261945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.769755Z","time spent":"492.147922ms","remote":"127.0.0.1:35902","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-04-23T10:31:02.262005Z","caller":"traceutil/trace.go:171","msg":"trace[476547899] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"364.695991ms","start":"2026-04-23T10:31:01.897289Z","end":"2026-04-23T10:31:02.261985Z","steps":["trace[476547899] 'process raft request' (duration: 364.540027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.262094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.897253Z","time spent":"364.812014ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T10:31:02.262105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.077199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T10:31:02.263725Z","caller":"traceutil/trace.go:171","msg":"trace[1281968303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"395.183092ms","start":"2026-04-23T10:31:01.866984Z","end":"2026-04-23T10:31:02.262167Z","steps":["trace[1281968303] 'agreement among raft nodes before linearized reading' (duration: 395.027868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.26405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.38629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T10:31:02.264088Z","caller":"traceutil/trace.go:171","msg":"trace[1341769026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"321.469242ms","start":"2026-04-23T10:31:01.942607Z","end":"2026-04-23T10:31:02.264076Z","steps":["trace[1341769026] 'agreement among raft nodes before linearized reading' (duration: 321.408211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.264103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.866966Z","time spent":"397.082283ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T10:31:02.264112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.942578Z","time spent":"321.528963ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T10:31:02.263941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.339985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T10:31:02.264482Z","caller":"traceutil/trace.go:171","msg":"trace[105394307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"395.905399ms","start":"2026-04-23T10:31:01.868569Z","end":"2026-04-23T10:31:02.264474Z","steps":["trace[105394307] 'agreement among raft nodes before linearized reading' (duration: 395.301164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.264507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:01.868555Z","time spent":"395.945229ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T10:31:02.974364Z","caller":"traceutil/trace.go:171","msg":"trace[237511497] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"440.351232ms","start":"2026-04-23T10:31:02.533976Z","end":"2026-04-23T10:31:02.974328Z","steps":["trace[237511497] 'process raft request' (duration: 439.978174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.974609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:02.533957Z","time spent":"440.544037ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T10:31:02.980015Z","caller":"traceutil/trace.go:171","msg":"trace[726694699] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:572; }","duration":"220.354681ms","start":"2026-04-23T10:31:02.759634Z","end":"2026-04-23T10:31:02.979989Z","steps":["trace[726694699] 'read index received' (duration: 214.316636ms)","trace[726694699] 'applied index is now lower than readState.Index' (duration: 6.036995ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:31:02.980202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.546185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T10:31:02.980245Z","caller":"traceutil/trace.go:171","msg":"trace[491243049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"220.683968ms","start":"2026-04-23T10:31:02.759549Z","end":"2026-04-23T10:31:02.980233Z","steps":["trace[491243049] 'agreement among raft nodes before linearized reading' (duration: 220.546645ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:02.980685Z","caller":"traceutil/trace.go:171","msg":"trace[1660364896] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"444.938296ms","start":"2026-04-23T10:31:02.535733Z","end":"2026-04-23T10:31:02.980671Z","steps":["trace[1660364896] 'process raft request' (duration: 444.003325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.9808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:02.535725Z","time spent":"444.999377ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:31:02.980935Z","caller":"traceutil/trace.go:171","msg":"trace[2106123458] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"433.11963ms","start":"2026-04-23T10:31:02.547806Z","end":"2026-04-23T10:31:02.980926Z","steps":["trace[2106123458] 'process raft request' (duration: 432.129269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:02.981021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:02.547785Z","time spent":"433.179461ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:31:08.459568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367202964670493,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T10:31:08.480436Z","caller":"traceutil/trace.go:171","msg":"trace[2024675702] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"521.446357ms","start":"2026-04-23T10:31:07.958963Z","end":"2026-04-23T10:31:08.480409Z","steps":["trace[2024675702] 'read index received' (duration: 519.545455ms)","trace[2024675702] 'applied index is now lower than readState.Index' (duration: 1.899852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:31:08.481522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.505781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.481698Z","caller":"traceutil/trace.go:171","msg":"trace[1272577956] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"547.315946ms","start":"2026-04-23T10:31:07.93434Z","end":"2026-04-23T10:31:08.481655Z","steps":["trace[1272577956] 'process raft request' (duration: 543.917031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.481727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.137392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T10:31:08.481819Z","caller":"traceutil/trace.go:171","msg":"trace[861229566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"143.326466ms","start":"2026-04-23T10:31:08.338469Z","end":"2026-04-23T10:31:08.481795Z","steps":["trace[861229566] 'agreement among raft nodes before linearized reading' (duration: 142.944548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.481862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:07.934302Z","time spent":"547.439849ms","remote":"127.0.0.1:35962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:31:08.482112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.002832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.482125Z","caller":"traceutil/trace.go:171","msg":"trace[1267162106] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:604; }","duration":"522.646745ms","start":"2026-04-23T10:31:07.958935Z","end":"2026-04-23T10:31:08.481582Z","steps":["trace[1267162106] 'agreement among raft nodes before linearized reading' (duration: 522.477111ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:08.482139Z","caller":"traceutil/trace.go:171","msg":"trace[1111907284] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:604; }","duration":"523.176446ms","start":"2026-04-23T10:31:07.958954Z","end":"2026-04-23T10:31:08.482131Z","steps":["trace[1111907284] 'agreement among raft nodes before linearized reading' (duration: 522.989662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.482166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:07.958946Z","time spent":"523.214876ms","remote":"127.0.0.1:55152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-23T10:31:08.482172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:07.958908Z","time spent":"523.254758ms","remote":"127.0.0.1:55152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-23T10:31:08.482697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.533153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T10:31:08.482717Z","caller":"traceutil/trace.go:171","msg":"trace[983878251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"144.572364ms","start":"2026-04-23T10:31:08.338138Z","end":"2026-04-23T10:31:08.48271Z","steps":["trace[983878251] 'agreement among raft nodes before linearized reading' (duration: 144.450961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.482829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.852532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T10:31:08.482844Z","caller":"traceutil/trace.go:171","msg":"trace[584832279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"149.897493ms","start":"2026-04-23T10:31:08.33294Z","end":"2026-04-23T10:31:08.482837Z","steps":["trace[584832279] 'agreement among raft nodes before linearized reading' (duration: 149.856062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.482911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.955319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.482925Z","caller":"traceutil/trace.go:171","msg":"trace[1300892150] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:604; }","duration":"193.98495ms","start":"2026-04-23T10:31:08.288934Z","end":"2026-04-23T10:31:08.482919Z","steps":["trace[1300892150] 'agreement among raft nodes before linearized reading' (duration: 193.95843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.838718ms","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":"info","ts":"2026-04-23T10:31:08.483035Z","caller":"traceutil/trace.go:171","msg":"trace[1762612140] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:604; }","duration":"194.87696ms","start":"2026-04-23T10:31:08.288153Z","end":"2026-04-23T10:31:08.48303Z","steps":["trace[1762612140] 'agreement among raft nodes before linearized reading' (duration: 194.849039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.079284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.483113Z","caller":"traceutil/trace.go:171","msg":"trace[357286760] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:604; }","duration":"195.110934ms","start":"2026-04-23T10:31:08.287997Z","end":"2026-04-23T10:31:08.483108Z","steps":["trace[357286760] 'agreement among raft nodes before linearized reading' (duration: 195.087184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.209536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.4832Z","caller":"traceutil/trace.go:171","msg":"trace[551800555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:604; }","duration":"228.235966ms","start":"2026-04-23T10:31:08.254957Z","end":"2026-04-23T10:31:08.483193Z","steps":["trace[551800555] 'agreement among raft nodes before linearized reading' (duration: 228.207456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.605384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.483304Z","caller":"traceutil/trace.go:171","msg":"trace[1141315663] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:604; }","duration":"228.631105ms","start":"2026-04-23T10:31:08.254665Z","end":"2026-04-23T10:31:08.483296Z","steps":["trace[1141315663] 'agreement among raft nodes before linearized reading' (duration: 228.606944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.84813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T10:31:08.483382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.737466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.483408Z","caller":"traceutil/trace.go:171","msg":"trace[1427961805] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:604; }","duration":"228.86388ms","start":"2026-04-23T10:31:08.254539Z","end":"2026-04-23T10:31:08.483403Z","steps":["trace[1427961805] 'agreement among raft nodes before linearized reading' (duration: 228.837859ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:08.483424Z","caller":"traceutil/trace.go:171","msg":"trace[1550355067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"101.778717ms","start":"2026-04-23T10:31:08.381634Z","end":"2026-04-23T10:31:08.483413Z","steps":["trace[1550355067] 'agreement among raft nodes before linearized reading' (duration: 100.890188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.244586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T10:31:08.483485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.632302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.483503Z","caller":"traceutil/trace.go:171","msg":"trace[1339052055] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:604; }","duration":"264.689983ms","start":"2026-04-23T10:31:08.218806Z","end":"2026-04-23T10:31:08.483496Z","steps":["trace[1339052055] 'agreement among raft nodes before linearized reading' (duration: 264.660692ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:08.483502Z","caller":"traceutil/trace.go:171","msg":"trace[1832818011] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:604; }","duration":"139.286017ms","start":"2026-04-23T10:31:08.344208Z","end":"2026-04-23T10:31:08.483494Z","steps":["trace[1832818011] 'agreement among raft nodes before linearized reading' (duration: 139.242325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.468551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T10:31:08.483579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.885597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.484927Z","caller":"traceutil/trace.go:171","msg":"trace[613091388] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:604; }","duration":"266.237967ms","start":"2026-04-23T10:31:08.21868Z","end":"2026-04-23T10:31:08.484918Z","steps":["trace[613091388] 'agreement among raft nodes before linearized reading' (duration: 264.872067ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:08.483598Z","caller":"traceutil/trace.go:171","msg":"trace[509460768] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:604; }","duration":"139.533242ms","start":"2026-04-23T10:31:08.344054Z","end":"2026-04-23T10:31:08.483588Z","steps":["trace[509460768] 'agreement among raft nodes before linearized reading' (duration: 139.468001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.651501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.485074Z","caller":"traceutil/trace.go:171","msg":"trace[224206129] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:604; }","duration":"114.108414ms","start":"2026-04-23T10:31:08.37096Z","end":"2026-04-23T10:31:08.485069Z","steps":["trace[224206129] 'agreement among raft nodes before linearized reading' (duration: 112.633391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.600399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.485189Z","caller":"traceutil/trace.go:171","msg":"trace[1070167891] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:604; }","duration":"526.111962ms","start":"2026-04-23T10:31:07.959025Z","end":"2026-04-23T10:31:08.485137Z","steps":["trace[1070167891] 'agreement among raft nodes before linearized reading' (duration: 524.581968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.015537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.485279Z","caller":"traceutil/trace.go:171","msg":"trace[2122372362] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:604; }","duration":"273.632283ms","start":"2026-04-23T10:31:08.211636Z","end":"2026-04-23T10:31:08.485269Z","steps":["trace[2122372362] 'agreement among raft nodes before linearized reading' (duration: 272.021377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.485359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:07.959011Z","time spent":"526.233744ms","remote":"127.0.0.1:55152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:31:08.483694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.060908ms","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-04-23T10:31:08.485407Z","caller":"traceutil/trace.go:171","msg":"trace[1052904535] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:604; }","duration":"273.778326ms","start":"2026-04-23T10:31:08.211623Z","end":"2026-04-23T10:31:08.485402Z","steps":["trace[1052904535] 'agreement among raft nodes before linearized reading' (duration: 272.061438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.097441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.485542Z","caller":"traceutil/trace.go:171","msg":"trace[946492411] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:604; }","duration":"114.926022ms","start":"2026-04-23T10:31:08.370592Z","end":"2026-04-23T10:31:08.485518Z","steps":["trace[946492411] 'agreement among raft nodes before linearized reading' (duration: 113.06432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.103819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.4856Z","caller":"traceutil/trace.go:171","msg":"trace[1387623905] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:604; }","duration":"274.010531ms","start":"2026-04-23T10:31:08.211579Z","end":"2026-04-23T10:31:08.48559Z","steps":["trace[1387623905] 'agreement among raft nodes before linearized reading' (duration: 272.123789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.966561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.485728Z","caller":"traceutil/trace.go:171","msg":"trace[959049094] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:604; }","duration":"473.958575ms","start":"2026-04-23T10:31:08.01176Z","end":"2026-04-23T10:31:08.485719Z","steps":["trace[959049094] 'agreement among raft nodes before linearized reading' (duration: 471.961431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.485757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:08.011704Z","time spent":"474.043647ms","remote":"127.0.0.1:55178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-04-23T10:31:08.483734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.153922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.485813Z","caller":"traceutil/trace.go:171","msg":"trace[444369832] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:604; }","duration":"115.244779ms","start":"2026-04-23T10:31:08.37056Z","end":"2026-04-23T10:31:08.485805Z","steps":["trace[444369832] 'agreement among raft nodes before linearized reading' (duration: 113.158263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.375683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.485867Z","caller":"traceutil/trace.go:171","msg":"trace[1108228782] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:604; }","duration":"484.500451ms","start":"2026-04-23T10:31:08.001355Z","end":"2026-04-23T10:31:08.485856Z","steps":["trace[1108228782] 'agreement among raft nodes before linearized reading' (duration: 482.384314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.485936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:08.001347Z","time spent":"484.538212ms","remote":"127.0.0.1:55178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:31:08.483762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.511342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.485975Z","caller":"traceutil/trace.go:171","msg":"trace[1437848084] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:604; }","duration":"141.766821ms","start":"2026-04-23T10:31:08.344197Z","end":"2026-04-23T10:31:08.485964Z","steps":["trace[1437848084] 'agreement among raft nodes before linearized reading' (duration: 139.548902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.483771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.535117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.486094Z","caller":"traceutil/trace.go:171","msg":"trace[57821069] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:604; }","duration":"484.883409ms","start":"2026-04-23T10:31:08.001201Z","end":"2026-04-23T10:31:08.486085Z","steps":["trace[57821069] 'agreement among raft nodes before linearized reading' (duration: 482.560688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.486126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:08.00119Z","time spent":"484.92877ms","remote":"127.0.0.1:55178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-04-23T10:31:08.483789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.678939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.486185Z","caller":"traceutil/trace.go:171","msg":"trace[649537975] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:604; }","duration":"506.073472ms","start":"2026-04-23T10:31:07.980106Z","end":"2026-04-23T10:31:08.48618Z","steps":["trace[649537975] 'agreement among raft nodes before linearized reading' (duration: 503.672769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.486204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:07.980086Z","time spent":"506.111824ms","remote":"127.0.0.1:55168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-23T10:31:08.48381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.407626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.486327Z","caller":"traceutil/trace.go:171","msg":"trace[209217947] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:604; }","duration":"506.919532ms","start":"2026-04-23T10:31:07.979386Z","end":"2026-04-23T10:31:08.486305Z","steps":["trace[209217947] 'agreement among raft nodes before linearized reading' (duration: 504.411896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.486393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:07.979378Z","time spent":"506.992913ms","remote":"127.0.0.1:55168","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-04-23T10:31:08.48383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.560449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:08.486466Z","caller":"traceutil/trace.go:171","msg":"trace[1283237502] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:604; }","duration":"507.233299ms","start":"2026-04-23T10:31:07.979226Z","end":"2026-04-23T10:31:08.486459Z","steps":["trace[1283237502] 'agreement among raft nodes before linearized reading' (duration: 504.5937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:08.486499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:07.979205Z","time spent":"507.280301ms","remote":"127.0.0.1:55168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"info","ts":"2026-04-23T10:31:09.50289Z","caller":"traceutil/trace.go:171","msg":"trace[1629417732] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"487.249522ms","start":"2026-04-23T10:31:09.015578Z","end":"2026-04-23T10:31:09.502827Z","steps":["trace[1629417732] 'read index received' (duration: 477.588017ms)","trace[1629417732] 'applied index is now lower than readState.Index' (duration: 9.660745ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:31:09.507301Z","caller":"traceutil/trace.go:171","msg":"trace[2125906988] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"571.551758ms","start":"2026-04-23T10:31:08.935712Z","end":"2026-04-23T10:31:09.507264Z","steps":["trace[2125906988] 'process raft request' (duration: 557.837451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:09.507792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.235859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:09.507839Z","caller":"traceutil/trace.go:171","msg":"trace[1035298087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"124.335211ms","start":"2026-04-23T10:31:09.383488Z","end":"2026-04-23T10:31:09.507823Z","steps":["trace[1035298087] 'agreement among raft nodes before linearized reading' (duration: 119.591775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:09.507924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:08.935678Z","time spent":"572.130611ms","remote":"127.0.0.1:35962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:31:09.507598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.000169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T10:31:09.508259Z","caller":"traceutil/trace.go:171","msg":"trace[702830950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"492.722564ms","start":"2026-04-23T10:31:09.015517Z","end":"2026-04-23T10:31:09.508239Z","steps":["trace[702830950] 'agreement among raft nodes before linearized reading' (duration: 487.552328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:09.508297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:09.015488Z","time spent":"492.800507ms","remote":"127.0.0.1:36132","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-04-23T10:31:09.77082Z","caller":"traceutil/trace.go:171","msg":"trace[107756757] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"256.156991ms","start":"2026-04-23T10:31:09.514604Z","end":"2026-04-23T10:31:09.770761Z","steps":["trace[107756757] 'process raft request' (duration: 164.979991ms)","trace[107756757] 'compare' (duration: 90.926215ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:31:12.043439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.123967352s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:31:12.043751Z","caller":"traceutil/trace.go:171","msg":"trace[1821216467] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"2.514806058s","start":"2026-04-23T10:31:09.528915Z","end":"2026-04-23T10:31:12.043722Z","steps":["trace[1821216467] 'process raft request' (duration: 390.419546ms)","trace[1821216467] 'compare' (duration: 2.123707017s)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:31:12.043903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:09.528881Z","time spent":"2.51493584s","remote":"127.0.0.1:36038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:31:12.043908Z","caller":"traceutil/trace.go:171","msg":"trace[2114516321] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"979.652909ms","start":"2026-04-23T10:31:11.064203Z","end":"2026-04-23T10:31:12.043856Z","steps":["trace[2114516321] 'process raft request' (duration: 979.455004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:12.044016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:11.064175Z","time spent":"979.788542ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:31:12.044112Z","caller":"traceutil/trace.go:171","msg":"trace[1924334544] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"1.58750183s","start":"2026-04-23T10:31:10.456598Z","end":"2026-04-23T10:31:12.0441Z","steps":["trace[1924334544] 'read index received' (duration: 78.782µs)","trace[1924334544] 'applied index is now lower than readState.Index' (duration: 1.587422238s)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:31:12.044182Z","caller":"traceutil/trace.go:171","msg":"trace[271852083] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.470526167s","start":"2026-04-23T10:31:09.57365Z","end":"2026-04-23T10:31:12.044176Z","steps":["trace[271852083] 'process raft request' (duration: 2.469913542s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:12.044223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:09.573606Z","time spent":"2.470595408s","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T10:31:12.044343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.587780216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:12.044393Z","caller":"traceutil/trace.go:171","msg":"trace[532550137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.587828867s","start":"2026-04-23T10:31:10.456554Z","end":"2026-04-23T10:31:12.044383Z","steps":["trace[532550137] 'agreement among raft nodes before linearized reading' (duration: 1.587743995s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:12.044426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:10.456478Z","time spent":"1.587939789s","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T10:31:12.044804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541162182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T10:31:12.044825Z","caller":"traceutil/trace.go:171","msg":"trace[760668764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.541233134s","start":"2026-04-23T10:31:10.503586Z","end":"2026-04-23T10:31:12.044819Z","steps":["trace[760668764] 'agreement among raft nodes before linearized reading' (duration: 1.541116431s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:12.044843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:10.503558Z","time spent":"1.541280675s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T10:31:12.045032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.69952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T10:31:12.045056Z","caller":"traceutil/trace.go:171","msg":"trace[382194630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"259.782783ms","start":"2026-04-23T10:31:11.785266Z","end":"2026-04-23T10:31:12.045049Z","steps":["trace[382194630] 'agreement among raft nodes before linearized reading' (duration: 259.718932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:12.045152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.978083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T10:31:12.0452Z","caller":"traceutil/trace.go:171","msg":"trace[168429768] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:616; }","duration":"915.081365ms","start":"2026-04-23T10:31:11.130112Z","end":"2026-04-23T10:31:12.045194Z","steps":["trace[168429768] 'agreement among raft nodes before linearized reading' (duration: 915.015254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:12.045217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:11.130071Z","time spent":"915.141437ms","remote":"127.0.0.1:35894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"warn","ts":"2026-04-23T10:31:12.045349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517697518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T10:31:12.045366Z","caller":"traceutil/trace.go:171","msg":"trace[1696285418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.5177924s","start":"2026-04-23T10:31:10.527569Z","end":"2026-04-23T10:31:12.045361Z","steps":["trace[1696285418] 'agreement among raft nodes before linearized reading' (duration: 1.517736559s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:12.045386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:10.527508Z","time spent":"1.517874392s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T10:31:12.045535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.531843264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T10:31:12.045555Z","caller":"traceutil/trace.go:171","msg":"trace[679147362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"1.531883975s","start":"2026-04-23T10:31:10.513666Z","end":"2026-04-23T10:31:12.04555Z","steps":["trace[679147362] 'agreement among raft nodes before linearized reading' (duration: 1.531786293s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:12.045573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:10.513654Z","time spent":"1.531913896s","remote":"127.0.0.1:36132","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-04-23T10:31:14.123555Z","caller":"traceutil/trace.go:171","msg":"trace[481119841] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"528.722393ms","start":"2026-04-23T10:31:13.594805Z","end":"2026-04-23T10:31:14.123527Z","steps":["trace[481119841] 'process raft request' (duration: 528.173301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:14.123716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:13.594772Z","time spent":"528.871916ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T10:31:14.130756Z","caller":"traceutil/trace.go:171","msg":"trace[1851465742] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"335.404397ms","start":"2026-04-23T10:31:13.795331Z","end":"2026-04-23T10:31:14.130735Z","steps":["trace[1851465742] 'process raft request' (duration: 335.248034ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:14.130796Z","caller":"traceutil/trace.go:171","msg":"trace[765337753] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"135.255318ms","start":"2026-04-23T10:31:13.995499Z","end":"2026-04-23T10:31:14.130755Z","steps":["trace[765337753] 'process raft request' (duration: 135.192306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:14.130924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:13.795298Z","time spent":"335.525889ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T10:31:27.884013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367202964670818,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T10:31:28.334063Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.163242004s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-23T10:31:28.661631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.259982ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T10:31:28.661777Z","caller":"traceutil/trace.go:171","msg":"trace[1183787519] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"1.277814223s","start":"2026-04-23T10:31:27.383942Z","end":"2026-04-23T10:31:28.661756Z","steps":["trace[1183787519] 'read index received' (duration: 950.392927ms)","trace[1183787519] 'applied index is now lower than readState.Index' (duration: 327.419556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:31:28.661888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277944146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:31:28.661963Z","caller":"traceutil/trace.go:171","msg":"trace[166863291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"1.278085439s","start":"2026-04-23T10:31:27.383859Z","end":"2026-04-23T10:31:28.661945Z","steps":["trace[166863291] 'agreement among raft nodes before linearized reading' (duration: 1.277966737s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:28.662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:27.383827Z","time spent":"1.27816518s","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T10:31:28.662027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.479232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T10:31:28.662077Z","caller":"traceutil/trace.go:171","msg":"trace[1252786783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:776; }","duration":"433.554844ms","start":"2026-04-23T10:31:28.228507Z","end":"2026-04-23T10:31:28.662062Z","steps":["trace[1252786783] 'agreement among raft nodes before linearized reading' (duration: 433.38664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:28.66211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:28.228498Z","time spent":"433.603865ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T10:31:28.662137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.891078ms","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-04-23T10:31:28.66214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.435525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-23T10:31:28.662174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.75344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-23T10:31:28.662233Z","caller":"traceutil/trace.go:171","msg":"trace[304200108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"440.02992ms","start":"2026-04-23T10:31:28.22218Z","end":"2026-04-23T10:31:28.66221Z","steps":["trace[304200108] 'agreement among raft nodes before linearized reading' (duration: 439.708163ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:28.662254Z","caller":"traceutil/trace.go:171","msg":"trace[947573409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"438.619009ms","start":"2026-04-23T10:31:28.223608Z","end":"2026-04-23T10:31:28.662227Z","steps":["trace[947573409] 'agreement among raft nodes before linearized reading' (duration: 438.332552ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:28.662261Z","caller":"traceutil/trace.go:171","msg":"trace[1686066091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"441.909853ms","start":"2026-04-23T10:31:28.22033Z","end":"2026-04-23T10:31:28.66224Z","steps":["trace[1686066091] 'agreement among raft nodes before linearized reading' (duration: 441.643667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:28.662289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:28.222171Z","time spent":"440.107472ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T10:31:28.662316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:28.220299Z","time spent":"442.003755ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T10:31:28.662315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:28.223578Z","time spent":"438.723041ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T10:31:28.662479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113179998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z8gkf\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-04-23T10:31:28.66249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197142303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T10:31:28.662511Z","caller":"traceutil/trace.go:171","msg":"trace[1655811495] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z8gkf; range_end:; response_count:1; response_revision:776; }","duration":"1.113294601s","start":"2026-04-23T10:31:27.549206Z","end":"2026-04-23T10:31:28.6625Z","steps":["trace[1655811495] 'agreement among raft nodes before linearized reading' (duration: 1.113196449s)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:31:28.662517Z","caller":"traceutil/trace.go:171","msg":"trace[1733359188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:776; }","duration":"1.197207735s","start":"2026-04-23T10:31:27.4653Z","end":"2026-04-23T10:31:28.662508Z","steps":["trace[1733359188] 'agreement among raft nodes before linearized reading' (duration: 1.196580341s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:31:28.66254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:27.549143Z","time spent":"1.113390514s","remote":"127.0.0.1:36038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z8gkf\" "} {"level":"warn","ts":"2026-04-23T10:31:28.662549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:31:27.465272Z","time spent":"1.197270576s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T10:32:04.049373Z","caller":"traceutil/trace.go:171","msg":"trace[771289844] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"117.113827ms","start":"2026-04-23T10:32:03.932226Z","end":"2026-04-23T10:32:04.04934Z","steps":["trace[771289844] 'process raft request' (duration: 115.010209ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:05.238702Z","caller":"traceutil/trace.go:171","msg":"trace[1573341896] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"341.388553ms","start":"2026-04-23T10:32:04.897283Z","end":"2026-04-23T10:32:05.238672Z","steps":["trace[1573341896] 'process raft request' (duration: 341.24756ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:05.238701Z","caller":"traceutil/trace.go:171","msg":"trace[1047931756] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1038; }","duration":"340.968333ms","start":"2026-04-23T10:32:04.897698Z","end":"2026-04-23T10:32:05.238667Z","steps":["trace[1047931756] 'read index received' (duration: 340.957102ms)","trace[1047931756] 'applied index is now lower than readState.Index' (duration: 9.391µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:32:05.238868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:04.897237Z","time spent":"341.544166ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:32:05.239029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.29228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T10:32:05.239077Z","caller":"traceutil/trace.go:171","msg":"trace[1076495632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"341.386542ms","start":"2026-04-23T10:32:04.897678Z","end":"2026-04-23T10:32:05.239065Z","steps":["trace[1076495632] 'agreement among raft nodes before linearized reading' (duration: 341.143157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:05.239121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:04.89767Z","time spent":"341.440803ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T10:32:05.74352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367202964671315,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T10:32:06.244595Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367202964671315,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T10:32:06.745414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367202964671315,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T10:32:06.786487Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.547884128s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T10:32:06.787058Z","caller":"traceutil/trace.go:171","msg":"trace[434222289] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.889032755s","start":"2026-04-23T10:32:04.897988Z","end":"2026-04-23T10:32:06.787021Z","steps":["trace[434222289] 'process raft request' (duration: 1.888754439s)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:06.787226Z","caller":"traceutil/trace.go:171","msg":"trace[1190947287] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.888291128s","start":"2026-04-23T10:32:04.898907Z","end":"2026-04-23T10:32:06.787198Z","steps":["trace[1190947287] 'process raft request' (duration: 1.888049693s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:06.787259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:04.897975Z","time spent":"1.889171108s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:32:06.78738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:04.898898Z","time spent":"1.88839242s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:32:06.789055Z","caller":"traceutil/trace.go:171","msg":"trace[823400257] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"729.393364ms","start":"2026-04-23T10:32:06.059649Z","end":"2026-04-23T10:32:06.789043Z","steps":["trace[823400257] 'process raft request' (duration: 728.804921ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:06.789031Z","caller":"traceutil/trace.go:171","msg":"trace[463021748] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.544165024s","start":"2026-04-23T10:32:05.24482Z","end":"2026-04-23T10:32:06.788985Z","steps":["trace[463021748] 'process raft request' (duration: 1.543488418s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:06.789108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:06.059619Z","time spent":"729.460636ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:32:06.789054Z","caller":"traceutil/trace.go:171","msg":"trace[927053553] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"967.163087ms","start":"2026-04-23T10:32:05.821865Z","end":"2026-04-23T10:32:06.789028Z","steps":["trace[927053553] 'process raft request' (duration: 966.550562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:06.78935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:05.82184Z","time spent":"967.442524ms","remote":"127.0.0.1:36132","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-04-23T10:32:06.789425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:05.244781Z","time spent":"1.544334288s","remote":"127.0.0.1:36132","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-04-23T10:32:06.790148Z","caller":"traceutil/trace.go:171","msg":"trace[486458694] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1038; }","duration":"1.551336116s","start":"2026-04-23T10:32:05.238797Z","end":"2026-04-23T10:32:06.790133Z","steps":["trace[486458694] 'read index received' (duration: 1.548038052s)","trace[486458694] 'applied index is now lower than readState.Index' (duration: 3.296944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:32:06.790462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.892032172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T10:32:06.790504Z","caller":"traceutil/trace.go:171","msg":"trace[225073983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"1.892099444s","start":"2026-04-23T10:32:04.898394Z","end":"2026-04-23T10:32:06.790493Z","steps":["trace[225073983] 'agreement among raft nodes before linearized reading' (duration: 1.891930071s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:06.790507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.399239156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T10:32:06.790541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:04.898388Z","time spent":"1.892139865s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T10:32:06.790572Z","caller":"traceutil/trace.go:171","msg":"trace[1060216711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"1.399334208s","start":"2026-04-23T10:32:05.391215Z","end":"2026-04-23T10:32:06.79055Z","steps":["trace[1060216711] 'agreement among raft nodes before linearized reading' (duration: 1.399236296s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:06.790614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:05.391183Z","time spent":"1.399425569s","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T10:32:06.790799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.959431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T10:32:06.790824Z","caller":"traceutil/trace.go:171","msg":"trace[99995190] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1004; }","duration":"128.007743ms","start":"2026-04-23T10:32:06.66281Z","end":"2026-04-23T10:32:06.790817Z","steps":["trace[99995190] 'agreement among raft nodes before linearized reading' (duration: 127.956152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:06.790938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.277864ms","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-04-23T10:32:06.790962Z","caller":"traceutil/trace.go:171","msg":"trace[1320062244] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1004; }","duration":"130.339076ms","start":"2026-04-23T10:32:06.660617Z","end":"2026-04-23T10:32:06.790956Z","steps":["trace[1320062244] 'agreement among raft nodes before linearized reading' (duration: 130.299845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:06.79109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.399043331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:32:06.791114Z","caller":"traceutil/trace.go:171","msg":"trace[973432875] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1004; }","duration":"1.399121582s","start":"2026-04-23T10:32:05.391986Z","end":"2026-04-23T10:32:06.791108Z","steps":["trace[973432875] 'agreement among raft nodes before linearized reading' (duration: 1.399082611s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:06.791133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:05.391956Z","time spent":"1.399172884s","remote":"127.0.0.1:36234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-04-23T10:32:19.044856Z","caller":"traceutil/trace.go:171","msg":"trace[1409976271] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"192.940758ms","start":"2026-04-23T10:32:18.851888Z","end":"2026-04-23T10:32:19.044829Z","steps":["trace[1409976271] 'read index received' (duration: 192.844156ms)","trace[1409976271] 'applied index is now lower than readState.Index' (duration: 95.512µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:32:19.045046Z","caller":"traceutil/trace.go:171","msg":"trace[1192293455] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"205.351592ms","start":"2026-04-23T10:32:18.839649Z","end":"2026-04-23T10:32:19.045Z","steps":["trace[1192293455] 'process raft request' (duration: 205.013245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:19.045141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.223715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T10:32:19.045344Z","caller":"traceutil/trace.go:171","msg":"trace[1307367235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1040; }","duration":"193.495891ms","start":"2026-04-23T10:32:18.851838Z","end":"2026-04-23T10:32:19.045333Z","steps":["trace[1307367235] 'agreement among raft nodes before linearized reading' (duration: 193.138733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:19.045341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.920353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T10:32:19.045407Z","caller":"traceutil/trace.go:171","msg":"trace[475996351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1040; }","duration":"184.030146ms","start":"2026-04-23T10:32:18.861358Z","end":"2026-04-23T10:32:19.045388Z","steps":["trace[475996351] 'agreement among raft nodes before linearized reading' (duration: 183.848572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:19.045297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.370016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T10:32:19.045509Z","caller":"traceutil/trace.go:171","msg":"trace[1297508585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1040; }","duration":"185.56276ms","start":"2026-04-23T10:32:18.859919Z","end":"2026-04-23T10:32:19.045481Z","steps":["trace[1297508585] 'agreement among raft nodes before linearized reading' (duration: 185.300604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:19.046645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.284182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T10:32:19.047865Z","caller":"traceutil/trace.go:171","msg":"trace[28797491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1040; }","duration":"193.611484ms","start":"2026-04-23T10:32:18.854221Z","end":"2026-04-23T10:32:19.047832Z","steps":["trace[28797491] 'agreement among raft nodes before linearized reading' (duration: 190.902402ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:33.292096Z","caller":"traceutil/trace.go:171","msg":"trace[1297461657] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"174.822076ms","start":"2026-04-23T10:32:33.117248Z","end":"2026-04-23T10:32:33.29207Z","steps":["trace[1297461657] 'process raft request' (duration: 174.056409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:33.805352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367202964671539,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T10:32:34.139917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.042009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:32:34.140145Z","caller":"traceutil/trace.go:171","msg":"trace[2131657707] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"836.527631ms","start":"2026-04-23T10:32:33.303592Z","end":"2026-04-23T10:32:34.14012Z","steps":["trace[2131657707] 'process raft request' (duration: 733.158025ms)","trace[2131657707] 'compare' (duration: 102.785623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:32:34.140233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:33.303551Z","time spent":"836.632444ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:32:34.16997Z","caller":"traceutil/trace.go:171","msg":"trace[254646848] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"863.599839ms","start":"2026-04-23T10:32:33.306313Z","end":"2026-04-23T10:32:34.169912Z","steps":["trace[254646848] 'process raft request' (duration: 863.404724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:34.170138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:33.306301Z","time spent":"863.750992ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:32:34.170351Z","caller":"traceutil/trace.go:171","msg":"trace[1891059193] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1131; }","duration":"865.901781ms","start":"2026-04-23T10:32:33.304418Z","end":"2026-04-23T10:32:34.17032Z","steps":["trace[1891059193] 'read index received' (duration: 732.297875ms)","trace[1891059193] 'applied index is now lower than readState.Index' (duration: 133.602596ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:32:34.170496Z","caller":"traceutil/trace.go:171","msg":"trace[913670336] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"307.023459ms","start":"2026-04-23T10:32:33.863459Z","end":"2026-04-23T10:32:34.170482Z","steps":["trace[913670336] 'process raft request' (duration: 306.718253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:34.170569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:33.863424Z","time spent":"307.110382ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:32:34.170639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.234618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T10:32:34.170664Z","caller":"traceutil/trace.go:171","msg":"trace[662188153] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"863.939245ms","start":"2026-04-23T10:32:33.306715Z","end":"2026-04-23T10:32:34.170654Z","steps":["trace[662188153] 'process raft request' (duration: 863.145998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:34.170622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.36915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T10:32:34.170711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:33.306699Z","time spent":"863.987927ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:32:34.170726Z","caller":"traceutil/trace.go:171","msg":"trace[712122562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"783.547884ms","start":"2026-04-23T10:32:33.387155Z","end":"2026-04-23T10:32:34.170702Z","steps":["trace[712122562] 'agreement among raft nodes before linearized reading' (duration: 783.35943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:34.170761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.42253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-23T10:32:34.170773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:33.387127Z","time spent":"783.637216ms","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T10:32:34.170794Z","caller":"traceutil/trace.go:171","msg":"trace[1815450850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"779.500841ms","start":"2026-04-23T10:32:33.391287Z","end":"2026-04-23T10:32:34.170788Z","steps":["trace[1815450850] 'agreement among raft nodes before linearized reading' (duration: 779.42839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:34.170828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:33.391267Z","time spent":"779.553872ms","remote":"127.0.0.1:36132","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-04-23T10:32:34.170676Z","caller":"traceutil/trace.go:171","msg":"trace[816127516] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"866.396582ms","start":"2026-04-23T10:32:33.30427Z","end":"2026-04-23T10:32:34.170667Z","steps":["trace[816127516] 'agreement among raft nodes before linearized reading' (duration: 866.211307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:34.170953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:33.304249Z","time spent":"866.644778ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T10:32:35.85039Z","caller":"traceutil/trace.go:171","msg":"trace[542937169] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"466.282981ms","start":"2026-04-23T10:32:35.384069Z","end":"2026-04-23T10:32:35.850352Z","steps":["trace[542937169] 'read index received' (duration: 465.905223ms)","trace[542937169] 'applied index is now lower than readState.Index' (duration: 376.058µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:32:35.850487Z","caller":"traceutil/trace.go:171","msg":"trace[1159460692] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"550.662995ms","start":"2026-04-23T10:32:35.29977Z","end":"2026-04-23T10:32:35.850433Z","steps":["trace[1159460692] 'process raft request' (duration: 550.311297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:35.850694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.073752ms","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-04-23T10:32:35.850717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.602679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:32:35.850766Z","caller":"traceutil/trace.go:171","msg":"trace[25233938] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1093; }","duration":"272.266187ms","start":"2026-04-23T10:32:35.57848Z","end":"2026-04-23T10:32:35.850747Z","steps":["trace[25233938] 'agreement among raft nodes before linearized reading' (duration: 272.088913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:35.85075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.017977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:32:35.850821Z","caller":"traceutil/trace.go:171","msg":"trace[112856661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"466.793273ms","start":"2026-04-23T10:32:35.384005Z","end":"2026-04-23T10:32:35.850798Z","steps":["trace[112856661] 'agreement among raft nodes before linearized reading' (duration: 466.538957ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:35.850831Z","caller":"traceutil/trace.go:171","msg":"trace[1798363109] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1093; }","duration":"181.19523ms","start":"2026-04-23T10:32:35.669613Z","end":"2026-04-23T10:32:35.850808Z","steps":["trace[1798363109] 'agreement among raft nodes before linearized reading' (duration: 181.002226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:35.850879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:35.383974Z","time spent":"466.896995ms","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T10:32:35.851464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:35.299741Z","time spent":"551.616847ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:32:36.664116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.230921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T10:32:36.664251Z","caller":"traceutil/trace.go:171","msg":"trace[1296424435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1093; }","duration":"488.445716ms","start":"2026-04-23T10:32:36.175776Z","end":"2026-04-23T10:32:36.664222Z","steps":["trace[1296424435] 'range keys from in-memory index tree' (duration: 487.947035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:36.664317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:36.175749Z","time spent":"488.555488ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T10:32:36.664342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.722254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T10:32:36.664448Z","caller":"traceutil/trace.go:171","msg":"trace[1362616944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"446.885728ms","start":"2026-04-23T10:32:36.217536Z","end":"2026-04-23T10:32:36.664422Z","steps":["trace[1362616944] 'range keys from in-memory index tree' (duration: 446.441698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:36.664504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:36.217512Z","time spent":"446.9815ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T10:32:36.664525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.980322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-23T10:32:36.664139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.458748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-23T10:32:36.664574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.90552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-23T10:32:36.664582Z","caller":"traceutil/trace.go:171","msg":"trace[187356492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"469.065004ms","start":"2026-04-23T10:32:36.195503Z","end":"2026-04-23T10:32:36.664568Z","steps":["trace[187356492] 'range keys from in-memory index tree' (duration: 468.803108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:36.664634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:36.195489Z","time spent":"469.134775ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T10:32:36.664645Z","caller":"traceutil/trace.go:171","msg":"trace[807001685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"519.011163ms","start":"2026-04-23T10:32:36.145607Z","end":"2026-04-23T10:32:36.664618Z","steps":["trace[807001685] 'range keys from in-memory index tree' (duration: 518.745387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:36.664697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:36.14557Z","time spent":"519.118255ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T10:32:36.664628Z","caller":"traceutil/trace.go:171","msg":"trace[107986723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"485.96975ms","start":"2026-04-23T10:32:36.178626Z","end":"2026-04-23T10:32:36.664596Z","steps":["trace[107986723] 'range keys from in-memory index tree' (duration: 485.256894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:36.664848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:36.178618Z","time spent":"486.207215ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T10:32:36.664144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.778835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:32:36.665788Z","caller":"traceutil/trace.go:171","msg":"trace[1932082828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"812.478624ms","start":"2026-04-23T10:32:35.853286Z","end":"2026-04-23T10:32:36.665765Z","steps":["trace[1932082828] 'range keys from in-memory index tree' (duration: 810.657913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:36.665843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:35.853261Z","time spent":"812.566566ms","remote":"127.0.0.1:35864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T10:32:36.829101Z","caller":"traceutil/trace.go:171","msg":"trace[1893159261] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"158.671448ms","start":"2026-04-23T10:32:36.670402Z","end":"2026-04-23T10:32:36.829074Z","steps":["trace[1893159261] 'process raft request' (duration: 158.494564ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:36.83748Z","caller":"traceutil/trace.go:171","msg":"trace[2047700234] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"166.851745ms","start":"2026-04-23T10:32:36.670605Z","end":"2026-04-23T10:32:36.837456Z","steps":["trace[2047700234] 'process raft request' (duration: 166.777483ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:36.837778Z","caller":"traceutil/trace.go:171","msg":"trace[915098327] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"167.155471ms","start":"2026-04-23T10:32:36.670577Z","end":"2026-04-23T10:32:36.837732Z","steps":["trace[915098327] 'process raft request' (duration: 166.686101ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:36.837896Z","caller":"traceutil/trace.go:171","msg":"trace[1901291316] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"167.15394ms","start":"2026-04-23T10:32:36.670718Z","end":"2026-04-23T10:32:36.837872Z","steps":["trace[1901291316] 'process raft request' (duration: 166.70298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:36.83803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.489381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:32:36.838078Z","caller":"traceutil/trace.go:171","msg":"trace[2002037681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"161.594114ms","start":"2026-04-23T10:32:36.676475Z","end":"2026-04-23T10:32:36.838069Z","steps":["trace[2002037681] 'agreement among raft nodes before linearized reading' (duration: 161.473071ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:36.837885Z","caller":"traceutil/trace.go:171","msg":"trace[474047473] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1139; }","duration":"161.345078ms","start":"2026-04-23T10:32:36.676514Z","end":"2026-04-23T10:32:36.837859Z","steps":["trace[474047473] 'read index received' (duration: 152.420404ms)","trace[474047473] 'applied index is now lower than readState.Index' (duration: 8.923334ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:32:36.838185Z","caller":"traceutil/trace.go:171","msg":"trace[1462117679] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"165.968394ms","start":"2026-04-23T10:32:36.672201Z","end":"2026-04-23T10:32:36.838169Z","steps":["trace[1462117679] 'process raft request' (duration: 165.540994ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:37.663986Z","caller":"traceutil/trace.go:171","msg":"trace[322358563] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"239.854648ms","start":"2026-04-23T10:32:37.424095Z","end":"2026-04-23T10:32:37.66395Z","steps":["trace[322358563] 'process raft request' (duration: 239.638123ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:38.198214Z","caller":"traceutil/trace.go:171","msg":"trace[1491426639] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"339.763666ms","start":"2026-04-23T10:32:37.858414Z","end":"2026-04-23T10:32:38.198178Z","steps":["trace[1491426639] 'process raft request' (duration: 339.543941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:38.198433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:37.858376Z","time spent":"339.93936ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:32:38.944111Z","caller":"traceutil/trace.go:171","msg":"trace[803320055] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"102.423566ms","start":"2026-04-23T10:32:38.841665Z","end":"2026-04-23T10:32:38.944089Z","steps":["trace[803320055] 'read index received' (duration: 102.218141ms)","trace[803320055] 'applied index is now lower than readState.Index' (duration: 204.165µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:32:38.944421Z","caller":"traceutil/trace.go:171","msg":"trace[458009602] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"104.330108ms","start":"2026-04-23T10:32:38.840075Z","end":"2026-04-23T10:32:38.944405Z","steps":["trace[458009602] 'process raft request' (duration: 103.865757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:38.944933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.070136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T10:32:38.945072Z","caller":"traceutil/trace.go:171","msg":"trace[1695856234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1101; }","duration":"102.266801ms","start":"2026-04-23T10:32:38.842774Z","end":"2026-04-23T10:32:38.945041Z","steps":["trace[1695856234] 'agreement among raft nodes before linearized reading' (duration: 101.885862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:38.945124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.435768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-23T10:32:38.945211Z","caller":"traceutil/trace.go:171","msg":"trace[1220524164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1101; }","duration":"103.609772ms","start":"2026-04-23T10:32:38.841587Z","end":"2026-04-23T10:32:38.945197Z","steps":["trace[1220524164] 'agreement among raft nodes before linearized reading' (duration: 103.438818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:38.94536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.486933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T10:32:38.945396Z","caller":"traceutil/trace.go:171","msg":"trace[1800152667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1101; }","duration":"101.600226ms","start":"2026-04-23T10:32:38.843786Z","end":"2026-04-23T10:32:38.945387Z","steps":["trace[1800152667] 'agreement among raft nodes before linearized reading' (duration: 101.513684ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:47.234465Z","caller":"traceutil/trace.go:171","msg":"trace[1515306981] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"167.362374ms","start":"2026-04-23T10:32:47.067066Z","end":"2026-04-23T10:32:47.234428Z","steps":["trace[1515306981] 'process raft request' (duration: 130.569675ms)","trace[1515306981] 'compare' (duration: 36.567574ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:32:47.23469Z","caller":"traceutil/trace.go:171","msg":"trace[1482270344] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"167.466277ms","start":"2026-04-23T10:32:47.067134Z","end":"2026-04-23T10:32:47.234601Z","steps":["trace[1482270344] 'process raft request' (duration: 167.246812ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:53.284913Z","caller":"traceutil/trace.go:171","msg":"trace[464634580] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"243.93013ms","start":"2026-04-23T10:32:53.040957Z","end":"2026-04-23T10:32:53.284887Z","steps":["trace[464634580] 'process raft request' (duration: 243.351557ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:53.284919Z","caller":"traceutil/trace.go:171","msg":"trace[1851062237] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"198.873634ms","start":"2026-04-23T10:32:53.085976Z","end":"2026-04-23T10:32:53.284849Z","steps":["trace[1851062237] 'read index received' (duration: 198.25941ms)","trace[1851062237] 'applied index is now lower than readState.Index' (duration: 613.084µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:32:53.285119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.128699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-23T10:32:53.28518Z","caller":"traceutil/trace.go:171","msg":"trace[2037657850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"199.233541ms","start":"2026-04-23T10:32:53.085917Z","end":"2026-04-23T10:32:53.28515Z","steps":["trace[2037657850] 'agreement among raft nodes before linearized reading' (duration: 199.049367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:53.285336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.612842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T10:32:53.285383Z","caller":"traceutil/trace.go:171","msg":"trace[1418537622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1199; }","duration":"103.707954ms","start":"2026-04-23T10:32:53.181662Z","end":"2026-04-23T10:32:53.28537Z","steps":["trace[1418537622] 'agreement among raft nodes before linearized reading' (duration: 103.540551ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:55.130084Z","caller":"traceutil/trace.go:171","msg":"trace[330706985] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"835.114896ms","start":"2026-04-23T10:32:54.294941Z","end":"2026-04-23T10:32:55.130056Z","steps":["trace[330706985] 'process raft request' (duration: 834.957653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:55.130287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:54.294916Z","time spent":"835.236939ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:32:55.713616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367202964671799,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T10:32:56.189807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:55.799131Z","time spent":"390.665045ms","remote":"127.0.0.1:58604","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-23T10:32:56.190043Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.060245299s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T10:32:56.190442Z","caller":"traceutil/trace.go:171","msg":"trace[2034978825] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"977.756318ms","start":"2026-04-23T10:32:55.21266Z","end":"2026-04-23T10:32:56.190416Z","steps":["trace[2034978825] 'read index received' (duration: 977.519823ms)","trace[2034978825] 'applied index is now lower than readState.Index' (duration: 235.565µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:32:56.190977Z","caller":"traceutil/trace.go:171","msg":"trace[510751189] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"1.673887178s","start":"2026-04-23T10:32:54.517053Z","end":"2026-04-23T10:32:56.19094Z","steps":["trace[510751189] 'process raft request' (duration: 1.673197392s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:56.191145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:54.517033Z","time spent":"1.674024651s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:32:56.191143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.77955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-23T10:32:56.191197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.540379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T10:32:56.191146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.972793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T10:32:56.191205Z","caller":"traceutil/trace.go:171","msg":"trace[1545056558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1205; }","duration":"902.884183ms","start":"2026-04-23T10:32:55.288301Z","end":"2026-04-23T10:32:56.191185Z","steps":["trace[1545056558] 'agreement among raft nodes before linearized reading' (duration: 902.645097ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:32:56.191223Z","caller":"traceutil/trace.go:171","msg":"trace[1574379536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1205; }","duration":"807.60011ms","start":"2026-04-23T10:32:55.383612Z","end":"2026-04-23T10:32:56.191212Z","steps":["trace[1574379536] 'agreement among raft nodes before linearized reading' (duration: 807.56969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:56.191253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:55.38359Z","time spent":"807.657952ms","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T10:32:56.191253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:55.28828Z","time spent":"902.963394ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T10:32:56.191257Z","caller":"traceutil/trace.go:171","msg":"trace[1701165055] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1205; }","duration":"767.158098ms","start":"2026-04-23T10:32:55.424072Z","end":"2026-04-23T10:32:56.19123Z","steps":["trace[1701165055] 'agreement among raft nodes before linearized reading' (duration: 766.931153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:56.191262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.716659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T10:32:56.191323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.654339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:32:56.191352Z","caller":"traceutil/trace.go:171","msg":"trace[371451468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1205; }","duration":"885.845853ms","start":"2026-04-23T10:32:55.30548Z","end":"2026-04-23T10:32:56.191326Z","steps":["trace[371451468] 'agreement among raft nodes before linearized reading' (duration: 885.543196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:56.19135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:55.424049Z","time spent":"767.280811ms","remote":"127.0.0.1:36230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-04-23T10:32:56.191393Z","caller":"traceutil/trace.go:171","msg":"trace[779506662] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1205; }","duration":"978.728251ms","start":"2026-04-23T10:32:55.212623Z","end":"2026-04-23T10:32:56.191351Z","steps":["trace[779506662] 'agreement among raft nodes before linearized reading' (duration: 978.64923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:56.191418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.962775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-23T10:32:56.191431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:55.212604Z","time spent":"978.818463ms","remote":"127.0.0.1:55066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:32:56.19143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:55.305468Z","time spent":"885.952125ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T10:32:56.19145Z","caller":"traceutil/trace.go:171","msg":"trace[1113911408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1205; }","duration":"889.022716ms","start":"2026-04-23T10:32:55.302417Z","end":"2026-04-23T10:32:56.19144Z","steps":["trace[1113911408] 'agreement among raft nodes before linearized reading' (duration: 888.925984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:56.191486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:55.302401Z","time spent":"889.078937ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T10:32:56.191154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.796017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-23T10:32:56.191518Z","caller":"traceutil/trace.go:171","msg":"trace[1952676254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"884.200886ms","start":"2026-04-23T10:32:55.307311Z","end":"2026-04-23T10:32:56.191512Z","steps":["trace[1952676254] 'agreement among raft nodes before linearized reading' (duration: 883.684604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:56.191537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:55.307304Z","time spent":"884.228026ms","remote":"127.0.0.1:36132","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-04-23T10:32:56.191605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.678549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T10:32:56.19163Z","caller":"traceutil/trace.go:171","msg":"trace[337794895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1205; }","duration":"885.74154ms","start":"2026-04-23T10:32:55.30588Z","end":"2026-04-23T10:32:56.191622Z","steps":["trace[337794895] 'agreement among raft nodes before linearized reading' (duration: 885.671969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:32:56.191655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:32:55.30587Z","time spent":"885.778631ms","remote":"127.0.0.1:36132","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-04-23T10:33:10.465759Z","caller":"traceutil/trace.go:171","msg":"trace[1034239203] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"139.200533ms","start":"2026-04-23T10:33:10.326511Z","end":"2026-04-23T10:33:10.465712Z","steps":["trace[1034239203] 'process raft request' (duration: 138.935057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:10.466087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.412041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T10:33:10.466183Z","caller":"traceutil/trace.go:171","msg":"trace[1318292184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1260; }","duration":"126.558115ms","start":"2026-04-23T10:33:10.339612Z","end":"2026-04-23T10:33:10.46617Z","steps":["trace[1318292184] 'agreement among raft nodes before linearized reading' (duration: 126.225337ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:10.465794Z","caller":"traceutil/trace.go:171","msg":"trace[1917294582] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"126.118225ms","start":"2026-04-23T10:33:10.339646Z","end":"2026-04-23T10:33:10.465765Z","steps":["trace[1917294582] 'read index received' (duration: 125.650854ms)","trace[1917294582] 'applied index is now lower than readState.Index' (duration: 464.361µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:10.617039Z","caller":"traceutil/trace.go:171","msg":"trace[406370736] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"145.168489ms","start":"2026-04-23T10:33:10.471831Z","end":"2026-04-23T10:33:10.616999Z","steps":["trace[406370736] 'process raft request' (duration: 123.098126ms)","trace[406370736] 'compare' (duration: 21.823008ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:10.617624Z","caller":"traceutil/trace.go:171","msg":"trace[1178068707] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"144.272538ms","start":"2026-04-23T10:33:10.473295Z","end":"2026-04-23T10:33:10.617568Z","steps":["trace[1178068707] 'process raft request' (duration: 143.651994ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:10.618002Z","caller":"traceutil/trace.go:171","msg":"trace[840603787] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"144.27429ms","start":"2026-04-23T10:33:10.473718Z","end":"2026-04-23T10:33:10.617992Z","steps":["trace[840603787] 'process raft request' (duration: 143.767927ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:10.618103Z","caller":"traceutil/trace.go:171","msg":"trace[865461679] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"143.315718ms","start":"2026-04-23T10:33:10.474773Z","end":"2026-04-23T10:33:10.618089Z","steps":["trace[865461679] 'process raft request' (duration: 143.180395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:11.758388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.417974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T10:33:11.758474Z","caller":"traceutil/trace.go:171","msg":"trace[1853918247] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1264; }","duration":"620.586407ms","start":"2026-04-23T10:33:11.137868Z","end":"2026-04-23T10:33:11.758455Z","steps":["trace[1853918247] 'range keys from in-memory index tree' (duration: 620.183668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:11.758511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:11.137835Z","time spent":"620.662419ms","remote":"127.0.0.1:35894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-04-23T10:33:12.133545Z","caller":"traceutil/trace.go:171","msg":"trace[1075523706] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"945.253599ms","start":"2026-04-23T10:33:11.188253Z","end":"2026-04-23T10:33:12.133507Z","steps":["trace[1075523706] 'process raft request' (duration: 945.048904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:12.133793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:11.188198Z","time spent":"945.441753ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:33:12.568417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.303978ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-23T10:33:12.568548Z","caller":"traceutil/trace.go:171","msg":"trace[1337105455] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1318; }","duration":"778.055407ms","start":"2026-04-23T10:33:11.790466Z","end":"2026-04-23T10:33:12.568522Z","steps":["trace[1337105455] 'read index received' (duration: 342.915068ms)","trace[1337105455] 'applied index is now lower than readState.Index' (duration: 435.138329ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:33:12.568674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:11.785912Z","time spent":"782.757283ms","remote":"127.0.0.1:35894","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-23T10:33:12.568977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.503287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:33:12.56902Z","caller":"traceutil/trace.go:171","msg":"trace[1942934879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1265; }","duration":"778.586729ms","start":"2026-04-23T10:33:11.790422Z","end":"2026-04-23T10:33:12.569009Z","steps":["trace[1942934879] 'agreement among raft nodes before linearized reading' (duration: 778.500357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:12.569253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:11.7904Z","time spent":"778.837724ms","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T10:33:12.569491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.901215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:33:12.569535Z","caller":"traceutil/trace.go:171","msg":"trace[652337673] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1265; }","duration":"549.992076ms","start":"2026-04-23T10:33:12.01953Z","end":"2026-04-23T10:33:12.569523Z","steps":["trace[652337673] 'agreement among raft nodes before linearized reading' (duration: 549.918205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:12.569566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:12.019495Z","time spent":"550.062658ms","remote":"127.0.0.1:33992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-04-23T10:33:12.758375Z","caller":"traceutil/trace.go:171","msg":"trace[557615130] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1320; }","duration":"136.231166ms","start":"2026-04-23T10:33:12.622108Z","end":"2026-04-23T10:33:12.758339Z","steps":["trace[557615130] 'read index received' (duration: 4.157215ms)","trace[557615130] 'applied index is now lower than readState.Index' (duration: 132.072651ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:12.758519Z","caller":"traceutil/trace.go:171","msg":"trace[1068511714] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"182.575342ms","start":"2026-04-23T10:33:12.575929Z","end":"2026-04-23T10:33:12.758504Z","steps":["trace[1068511714] 'process raft request' (duration: 155.992875ms)","trace[1068511714] 'compare' (duration: 26.297251ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:33:12.758716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.561994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T10:33:12.758764Z","caller":"traceutil/trace.go:171","msg":"trace[1224484542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"136.698227ms","start":"2026-04-23T10:33:12.622056Z","end":"2026-04-23T10:33:12.758754Z","steps":["trace[1224484542] 'agreement among raft nodes before linearized reading' (duration: 136.502782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:12.759721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.71485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-23T10:33:12.759761Z","caller":"traceutil/trace.go:171","msg":"trace[1669219997] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1267; }","duration":"130.779982ms","start":"2026-04-23T10:33:12.628971Z","end":"2026-04-23T10:33:12.759752Z","steps":["trace[1669219997] 'agreement among raft nodes before linearized reading' (duration: 130.69776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:12.759822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.86102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T10:33:12.759916Z","caller":"traceutil/trace.go:171","msg":"trace[1296040048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"137.002973ms","start":"2026-04-23T10:33:12.622889Z","end":"2026-04-23T10:33:12.759892Z","steps":["trace[1296040048] 'agreement among raft nodes before linearized reading' (duration: 136.632055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:12.759953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.361218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-23T10:33:12.760044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.023283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T10:33:12.760071Z","caller":"traceutil/trace.go:171","msg":"trace[1835764585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"137.064875ms","start":"2026-04-23T10:33:12.622998Z","end":"2026-04-23T10:33:12.760063Z","steps":["trace[1835764585] 'agreement among raft nodes before linearized reading' (duration: 136.986173ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:12.760064Z","caller":"traceutil/trace.go:171","msg":"trace[1260704721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1267; }","duration":"136.534071ms","start":"2026-04-23T10:33:12.623509Z","end":"2026-04-23T10:33:12.760043Z","steps":["trace[1260704721] 'agreement among raft nodes before linearized reading' (duration: 136.151193ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:12.975341Z","caller":"traceutil/trace.go:171","msg":"trace[1805124210] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"208.113634ms","start":"2026-04-23T10:33:12.767205Z","end":"2026-04-23T10:33:12.975319Z","steps":["trace[1805124210] 'process raft request' (duration: 208.047092ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:12.975417Z","caller":"traceutil/trace.go:171","msg":"trace[1790048151] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"209.746992ms","start":"2026-04-23T10:33:12.765607Z","end":"2026-04-23T10:33:12.975354Z","steps":["trace[1790048151] 'process raft request' (duration: 119.153647ms)","trace[1790048151] 'compare' (duration: 90.323539ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:12.97551Z","caller":"traceutil/trace.go:171","msg":"trace[475477864] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"209.799933ms","start":"2026-04-23T10:33:12.765698Z","end":"2026-04-23T10:33:12.975498Z","steps":["trace[475477864] 'process raft request' (duration: 209.521047ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:12.975598Z","caller":"traceutil/trace.go:171","msg":"trace[1682068110] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"187.677138ms","start":"2026-04-23T10:33:12.787915Z","end":"2026-04-23T10:33:12.975592Z","steps":["trace[1682068110] 'process raft request' (duration: 187.363761ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:14.270773Z","caller":"traceutil/trace.go:171","msg":"trace[763163675] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"238.60744ms","start":"2026-04-23T10:33:14.032139Z","end":"2026-04-23T10:33:14.270746Z","steps":["trace[763163675] 'process raft request' (duration: 238.466466ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:14.278616Z","caller":"traceutil/trace.go:171","msg":"trace[319352113] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"139.013718ms","start":"2026-04-23T10:33:14.139579Z","end":"2026-04-23T10:33:14.278593Z","steps":["trace[319352113] 'process raft request' (duration: 138.881485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:14.930721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.182712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-23T10:33:14.930737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.061521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-23T10:33:14.93081Z","caller":"traceutil/trace.go:171","msg":"trace[525318119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1274; }","duration":"167.353765ms","start":"2026-04-23T10:33:14.763436Z","end":"2026-04-23T10:33:14.930789Z","steps":["trace[525318119] 'range keys from in-memory index tree' (duration: 166.888785ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:14.930854Z","caller":"traceutil/trace.go:171","msg":"trace[2143697507] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1274; }","duration":"411.241724ms","start":"2026-04-23T10:33:14.519586Z","end":"2026-04-23T10:33:14.930827Z","steps":["trace[2143697507] 'range keys from in-memory index tree' (duration: 410.857996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:14.930909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:14.519577Z","time spent":"411.322167ms","remote":"127.0.0.1:36034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-23T10:33:14.930931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.052441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-23T10:33:14.931027Z","caller":"traceutil/trace.go:171","msg":"trace[796452566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"132.207684ms","start":"2026-04-23T10:33:14.798794Z","end":"2026-04-23T10:33:14.931002Z","steps":["trace[796452566] 'range keys from in-memory index tree' (duration: 131.796085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:14.931236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.161315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:33:14.931267Z","caller":"traceutil/trace.go:171","msg":"trace[720438539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"548.267078ms","start":"2026-04-23T10:33:14.382989Z","end":"2026-04-23T10:33:14.931256Z","steps":["trace[720438539] 'range keys from in-memory index tree' (duration: 548.034813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:14.93128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.767893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-23T10:33:14.931295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:14.382958Z","time spent":"548.329149ms","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T10:33:14.931304Z","caller":"traceutil/trace.go:171","msg":"trace[69247456] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1274; }","duration":"510.825415ms","start":"2026-04-23T10:33:14.420468Z","end":"2026-04-23T10:33:14.931294Z","steps":["trace[69247456] 'count revisions from in-memory index tree' (duration: 510.679561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:14.931479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:14.42045Z","time spent":"511.013648ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-23T10:33:15.074449Z","caller":"traceutil/trace.go:171","msg":"trace[1626710339] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"137.577226ms","start":"2026-04-23T10:33:14.936819Z","end":"2026-04-23T10:33:15.074397Z","steps":["trace[1626710339] 'process raft request' (duration: 117.049908ms)","trace[1626710339] 'compare' (duration: 20.291902ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:15.074596Z","caller":"traceutil/trace.go:171","msg":"trace[1797822623] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"134.184117ms","start":"2026-04-23T10:33:14.94038Z","end":"2026-04-23T10:33:15.074564Z","steps":["trace[1797822623] 'process raft request' (duration: 133.934222ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:15.074796Z","caller":"traceutil/trace.go:171","msg":"trace[1741218397] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"106.19315ms","start":"2026-04-23T10:33:14.968593Z","end":"2026-04-23T10:33:15.074786Z","steps":["trace[1741218397] 'process raft request' (duration: 105.77537ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:15.246565Z","caller":"traceutil/trace.go:171","msg":"trace[925253472] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1333; }","duration":"164.714885ms","start":"2026-04-23T10:33:15.081821Z","end":"2026-04-23T10:33:15.246536Z","steps":["trace[925253472] 'read index received' (duration: 23.899586ms)","trace[925253472] 'applied index is now lower than readState.Index' (duration: 140.813829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:33:15.246758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.91711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-23T10:33:15.246789Z","caller":"traceutil/trace.go:171","msg":"trace[2018655318] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1282; }","duration":"164.998321ms","start":"2026-04-23T10:33:15.081781Z","end":"2026-04-23T10:33:15.246779Z","steps":["trace[2018655318] 'agreement among raft nodes before linearized reading' (duration: 164.864358ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:15.24692Z","caller":"traceutil/trace.go:171","msg":"trace[1532398679] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"166.637839ms","start":"2026-04-23T10:33:15.080246Z","end":"2026-04-23T10:33:15.246884Z","steps":["trace[1532398679] 'process raft request' (duration: 166.112517ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:15.247001Z","caller":"traceutil/trace.go:171","msg":"trace[1462655870] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"165.224946ms","start":"2026-04-23T10:33:15.081763Z","end":"2026-04-23T10:33:15.246988Z","steps":["trace[1462655870] 'process raft request' (duration: 164.721455ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:15.247037Z","caller":"traceutil/trace.go:171","msg":"trace[55512708] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"167.655752ms","start":"2026-04-23T10:33:15.079341Z","end":"2026-04-23T10:33:15.246997Z","steps":["trace[55512708] 'process raft request' (duration: 121.993231ms)","trace[55512708] 'compare' (duration: 44.673788ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:16.403327Z","caller":"traceutil/trace.go:171","msg":"trace[1476340419] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"119.088545ms","start":"2026-04-23T10:33:16.284203Z","end":"2026-04-23T10:33:16.403292Z","steps":["trace[1476340419] 'process raft request' (duration: 118.85905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:16.803219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.922693ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-23T10:33:16.803377Z","caller":"traceutil/trace.go:171","msg":"trace[1185601511] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1338; }","duration":"419.439741ms","start":"2026-04-23T10:33:16.383911Z","end":"2026-04-23T10:33:16.803351Z","steps":["trace[1185601511] 'read index received' (duration: 19.234948ms)","trace[1185601511] 'applied index is now lower than readState.Index' (duration: 400.202863ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:33:16.803499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.578445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:33:16.803538Z","caller":"traceutil/trace.go:171","msg":"trace[1113537063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"419.670227ms","start":"2026-04-23T10:33:16.383857Z","end":"2026-04-23T10:33:16.803527Z","steps":["trace[1113537063] 'agreement among raft nodes before linearized reading' (duration: 419.572205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:16.803596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:16.383832Z","time spent":"419.754998ms","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T10:33:17.080979Z","caller":"traceutil/trace.go:171","msg":"trace[714580478] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"275.86539ms","start":"2026-04-23T10:33:16.805097Z","end":"2026-04-23T10:33:17.080962Z","steps":["trace[714580478] 'read index received' (duration: 275.739087ms)","trace[714580478] 'applied index is now lower than readState.Index' (duration: 125.253µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:17.081012Z","caller":"traceutil/trace.go:171","msg":"trace[807154765] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"553.900107ms","start":"2026-04-23T10:33:16.527098Z","end":"2026-04-23T10:33:17.080998Z","steps":["trace[807154765] 'process raft request' (duration: 553.721733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:17.081119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.009492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:33:17.081211Z","caller":"traceutil/trace.go:171","msg":"trace[313574162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"276.119075ms","start":"2026-04-23T10:33:16.805076Z","end":"2026-04-23T10:33:17.081195Z","steps":["trace[313574162] 'agreement among raft nodes before linearized reading' (duration: 275.952581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:17.081213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:16.527066Z","time spent":"554.02412ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:33:17.219278Z","caller":"traceutil/trace.go:171","msg":"trace[1068616600] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1341; }","duration":"109.946957ms","start":"2026-04-23T10:33:17.109306Z","end":"2026-04-23T10:33:17.219253Z","steps":["trace[1068616600] 'read index received' (duration: 81.14429ms)","trace[1068616600] 'applied index is now lower than readState.Index' (duration: 28.801557ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:33:17.21951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.198302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T10:33:17.219543Z","caller":"traceutil/trace.go:171","msg":"trace[759780866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"110.287934ms","start":"2026-04-23T10:33:17.109244Z","end":"2026-04-23T10:33:17.219532Z","steps":["trace[759780866] 'agreement among raft nodes before linearized reading' (duration: 110.1065ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:17.21981Z","caller":"traceutil/trace.go:171","msg":"trace[761410925] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"134.68251ms","start":"2026-04-23T10:33:17.085114Z","end":"2026-04-23T10:33:17.219797Z","steps":["trace[761410925] 'process raft request' (duration: 105.320691ms)","trace[761410925] 'compare' (duration: 28.634953ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:17.219965Z","caller":"traceutil/trace.go:171","msg":"trace[47186664] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"134.429995ms","start":"2026-04-23T10:33:17.085494Z","end":"2026-04-23T10:33:17.219924Z","steps":["trace[47186664] 'process raft request' (duration: 133.718119ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:17.385896Z","caller":"traceutil/trace.go:171","msg":"trace[382761846] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"127.723292ms","start":"2026-04-23T10:33:17.258147Z","end":"2026-04-23T10:33:17.385871Z","steps":["trace[382761846] 'process raft request' (duration: 68.397519ms)","trace[382761846] 'compare' (duration: 59.202729ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:20.12815Z","caller":"traceutil/trace.go:171","msg":"trace[707299297] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"145.935206ms","start":"2026-04-23T10:33:19.982159Z","end":"2026-04-23T10:33:20.128095Z","steps":["trace[707299297] 'process raft request' (duration: 107.115482ms)","trace[707299297] 'compare' (duration: 38.63303ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:27.555111Z","caller":"traceutil/trace.go:171","msg":"trace[442567079] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"288.195309ms","start":"2026-04-23T10:33:27.266893Z","end":"2026-04-23T10:33:27.555088Z","steps":["trace[442567079] 'read index received' (duration: 288.093217ms)","trace[442567079] 'applied index is now lower than readState.Index' (duration: 101.292µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:33:27.55517Z","caller":"traceutil/trace.go:171","msg":"trace[555629151] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"508.662734ms","start":"2026-04-23T10:33:27.046472Z","end":"2026-04-23T10:33:27.555135Z","steps":["trace[555629151] 'process raft request' (duration: 508.495011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:27.55529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.392444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-23T10:33:27.555319Z","caller":"traceutil/trace.go:171","msg":"trace[1517141511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1368; }","duration":"288.473216ms","start":"2026-04-23T10:33:27.266837Z","end":"2026-04-23T10:33:27.55531Z","steps":["trace[1517141511] 'agreement among raft nodes before linearized reading' (duration: 288.360184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:27.555318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:27.046454Z","time spent":"508.787887ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:33:28.055252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367202964672139,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T10:33:28.492104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.022879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T10:33:28.492235Z","caller":"traceutil/trace.go:171","msg":"trace[1198043510] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"1.210089975s","start":"2026-04-23T10:33:27.28212Z","end":"2026-04-23T10:33:28.49221Z","steps":["trace[1198043510] 'process raft request' (duration: 785.857621ms)","trace[1198043510] 'compare' (duration: 423.699652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:33:28.492314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:27.282101Z","time spent":"1.210171526s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T10:33:28.818692Z","caller":"traceutil/trace.go:171","msg":"trace[132444115] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"1.259453029s","start":"2026-04-23T10:33:27.559208Z","end":"2026-04-23T10:33:28.818661Z","steps":["trace[132444115] 'process raft request' (duration: 1.259229824s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:28.818878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:27.559184Z","time spent":"1.259598632s","remote":"127.0.0.1:36132","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-04-23T10:33:28.824967Z","caller":"traceutil/trace.go:171","msg":"trace[1780778543] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1425; }","duration":"1.269758495s","start":"2026-04-23T10:33:27.555181Z","end":"2026-04-23T10:33:28.82494Z","steps":["trace[1780778543] 'read index received' (duration: 512.772708ms)","trace[1780778543] 'applied index is now lower than readState.Index' (duration: 756.983427ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:33:28.825079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440250071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:33:28.825121Z","caller":"traceutil/trace.go:171","msg":"trace[471686945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1372; }","duration":"1.440346243s","start":"2026-04-23T10:33:27.384763Z","end":"2026-04-23T10:33:28.825109Z","steps":["trace[471686945] 'agreement among raft nodes before linearized reading' (duration: 1.440271971s)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:28.825081Z","caller":"traceutil/trace.go:171","msg":"trace[587422848] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"441.610304ms","start":"2026-04-23T10:33:28.383431Z","end":"2026-04-23T10:33:28.825042Z","steps":["trace[587422848] 'process raft request' (duration: 441.400529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:28.825148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:27.384741Z","time spent":"1.440400684s","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T10:33:28.824976Z","caller":"traceutil/trace.go:171","msg":"trace[1772742239] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"335.23737ms","start":"2026-04-23T10:33:28.489712Z","end":"2026-04-23T10:33:28.82495Z","steps":["trace[1772742239] 'process raft request' (duration: 335.20611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:28.825301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392639208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-23T10:33:28.825346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:28.383411Z","time spent":"441.788917ms","remote":"127.0.0.1:36132","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-04-23T10:33:28.825382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.391508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-23T10:33:28.825373Z","caller":"traceutil/trace.go:171","msg":"trace[1788619002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1372; }","duration":"1.39274722s","start":"2026-04-23T10:33:27.432607Z","end":"2026-04-23T10:33:28.825354Z","steps":["trace[1788619002] 'agreement among raft nodes before linearized reading' (duration: 1.392474743s)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:28.825413Z","caller":"traceutil/trace.go:171","msg":"trace[2021059322] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1372; }","duration":"899.45176ms","start":"2026-04-23T10:33:27.925948Z","end":"2026-04-23T10:33:28.8254Z","steps":["trace[2021059322] 'agreement among raft nodes before linearized reading' (duration: 899.388248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:28.825429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:27.432591Z","time spent":"1.392822422s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T10:33:28.825491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.667527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T10:33:28.825515Z","caller":"traceutil/trace.go:171","msg":"trace[379927418] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1372; }","duration":"438.721787ms","start":"2026-04-23T10:33:28.386787Z","end":"2026-04-23T10:33:28.825509Z","steps":["trace[379927418] 'agreement among raft nodes before linearized reading' (duration: 438.679336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:28.825527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.592951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-04-23T10:33:28.82554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:28.386779Z","time spent":"438.755348ms","remote":"127.0.0.1:36344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:33:28.825308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469247387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T10:33:28.825562Z","caller":"traceutil/trace.go:171","msg":"trace[1224406678] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1372; }","duration":"272.667043ms","start":"2026-04-23T10:33:28.552887Z","end":"2026-04-23T10:33:28.825554Z","steps":["trace[1224406678] 'agreement among raft nodes before linearized reading' (duration: 272.555471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:28.825558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530454703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-23T10:33:28.825587Z","caller":"traceutil/trace.go:171","msg":"trace[1931636345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1372; }","duration":"1.469564504s","start":"2026-04-23T10:33:27.356005Z","end":"2026-04-23T10:33:28.825569Z","steps":["trace[1931636345] 'agreement among raft nodes before linearized reading' (duration: 1.469144074s)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:33:28.825622Z","caller":"traceutil/trace.go:171","msg":"trace[925862104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1372; }","duration":"1.530543564s","start":"2026-04-23T10:33:27.295063Z","end":"2026-04-23T10:33:28.825607Z","steps":["trace[925862104] 'agreement among raft nodes before linearized reading' (duration: 1.530392321s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:28.825674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557871363s","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-04-23T10:33:28.825669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:27.29505Z","time spent":"1.530608687s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T10:33:28.825697Z","caller":"traceutil/trace.go:171","msg":"trace[142425080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1372; }","duration":"1.557938774s","start":"2026-04-23T10:33:27.267752Z","end":"2026-04-23T10:33:28.825691Z","steps":["trace[142425080] 'agreement among raft nodes before linearized reading' (duration: 1.557881083s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:33:28.825668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:27.355985Z","time spent":"1.469670026s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-23T10:33:28.825412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:28.489684Z","time spent":"335.664571ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T10:33:28.825718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:27.267727Z","time spent":"1.557987125s","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-23T10:33:28.825446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:33:27.925926Z","time spent":"899.513541ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-23T10:36:51.807469Z","caller":"traceutil/trace.go:171","msg":"trace[265318196] linearizableReadLoop","detail":"{readStateIndex:2430; appliedIndex:2429; }","duration":"421.396323ms","start":"2026-04-23T10:36:51.386045Z","end":"2026-04-23T10:36:51.807442Z","steps":["trace[265318196] 'read index received' (duration: 421.2933ms)","trace[265318196] 'applied index is now lower than readState.Index' (duration: 101.312µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T10:36:51.807549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.482634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:36:51.807611Z","caller":"traceutil/trace.go:171","msg":"trace[2049400388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2330; }","duration":"421.580056ms","start":"2026-04-23T10:36:51.386018Z","end":"2026-04-23T10:36:51.807598Z","steps":["trace[2049400388] 'agreement among raft nodes before linearized reading' (duration: 421.460523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:36:51.807644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:36:51.386002Z","time spent":"421.637398ms","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-23T10:36:51.807891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:36:51.15154Z","time spent":"656.348838ms","remote":"127.0.0.1:35894","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-23T10:36:51.81019Z","caller":"traceutil/trace.go:171","msg":"trace[606669171] transaction","detail":"{read_only:false; response_revision:2331; number_of_response:1; }","duration":"240.085373ms","start":"2026-04-23T10:36:51.570073Z","end":"2026-04-23T10:36:51.810158Z","steps":["trace[606669171] 'process raft request' (duration: 239.906489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:36:51.810305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.503085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:36:51.810349Z","caller":"traceutil/trace.go:171","msg":"trace[1589373152] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2331; }","duration":"421.570016ms","start":"2026-04-23T10:36:51.388766Z","end":"2026-04-23T10:36:51.810336Z","steps":["trace[1589373152] 'agreement among raft nodes before linearized reading' (duration: 421.472874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:36:51.810379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T10:36:51.388705Z","time spent":"421.666518ms","remote":"127.0.0.1:55212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T10:37:02.292978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.666979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-23T10:37:02.29311Z","caller":"traceutil/trace.go:171","msg":"trace[1040793262] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:2390; }","duration":"110.865303ms","start":"2026-04-23T10:37:02.182218Z","end":"2026-04-23T10:37:02.293084Z","steps":["trace[1040793262] 'range keys from in-memory index tree' (duration: 110.387902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:37:02.293134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.244242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:expand-controller\" ","response":"range_response_count:1 size:880"} {"level":"info","ts":"2026-04-23T10:37:02.293323Z","caller":"traceutil/trace.go:171","msg":"trace[422701382] transaction","detail":"{read_only:false; response_revision:2391; number_of_response:1; }","duration":"111.050347ms","start":"2026-04-23T10:37:02.182246Z","end":"2026-04-23T10:37:02.293296Z","steps":["trace[422701382] 'process raft request' (duration: 71.02411ms)","trace[422701382] 'compare' (duration: 39.640198ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:37:02.293333Z","caller":"traceutil/trace.go:171","msg":"trace[1761694156] range","detail":"{range_begin:/registry/clusterroles/system:controller:expand-controller; range_end:; response_count:1; response_revision:2390; }","duration":"111.461847ms","start":"2026-04-23T10:37:02.181817Z","end":"2026-04-23T10:37:02.293279Z","steps":["trace[1761694156] 'range keys from in-memory index tree' (duration: 111.12612ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:37:02.959348Z","caller":"traceutil/trace.go:171","msg":"trace[314633684] linearizableReadLoop","detail":"{readStateIndex:2495; appliedIndex:2494; }","duration":"130.715254ms","start":"2026-04-23T10:37:02.828578Z","end":"2026-04-23T10:37:02.959293Z","steps":["trace[314633684] 'read index received' (duration: 130.54163ms)","trace[314633684] 'applied index is now lower than readState.Index' (duration: 172.354µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T10:37:02.959365Z","caller":"traceutil/trace.go:171","msg":"trace[2133172315] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"134.606612ms","start":"2026-04-23T10:37:02.824724Z","end":"2026-04-23T10:37:02.959331Z","steps":["trace[2133172315] 'process raft request' (duration: 134.381177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:37:02.959587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.97334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:get-nodes\" ","response":"range_response_count:1 size:469"} {"level":"info","ts":"2026-04-23T10:37:02.959691Z","caller":"traceutil/trace.go:171","msg":"trace[41121401] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:get-nodes; range_end:; response_count:1; response_revision:2392; }","duration":"131.117893ms","start":"2026-04-23T10:37:02.82854Z","end":"2026-04-23T10:37:02.959658Z","steps":["trace[41121401] 'agreement among raft nodes before linearized reading' (duration: 130.883568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:37:02.971021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.669419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:780"} {"level":"info","ts":"2026-04-23T10:37:02.97107Z","caller":"traceutil/trace.go:171","msg":"trace[377368500] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:2393; }","duration":"140.73742ms","start":"2026-04-23T10:37:02.830321Z","end":"2026-04-23T10:37:02.971058Z","steps":["trace[377368500] 'agreement among raft nodes before linearized reading' (duration: 140.630568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:37:20.196721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.338197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:671"} {"level":"warn","ts":"2026-04-23T10:37:20.19678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.738116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-23T10:37:20.196848Z","caller":"traceutil/trace.go:171","msg":"trace[563185450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2469; }","duration":"101.864379ms","start":"2026-04-23T10:37:20.094967Z","end":"2026-04-23T10:37:20.196831Z","steps":["trace[563185450] 'range keys from in-memory index tree' (duration: 101.552722ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T10:37:20.196849Z","caller":"traceutil/trace.go:171","msg":"trace[2067265527] range","detail":"{range_begin:/registry/roles/kube-system/extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:2469; }","duration":"112.520861ms","start":"2026-04-23T10:37:20.084298Z","end":"2026-04-23T10:37:20.196819Z","steps":["trace[2067265527] 'range keys from in-memory index tree' (duration: 112.178853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T10:37:31.502662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.750145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T10:37:31.502741Z","caller":"traceutil/trace.go:171","msg":"trace[171383723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2523; }","duration":"119.850737ms","start":"2026-04-23T10:37:31.382872Z","end":"2026-04-23T10:37:31.502723Z","steps":["trace[171383723] 'range keys from in-memory index tree' (duration: 119.713804ms)"],"step_count":1}