{"level":"warn","ts":"2026-04-01T15:43:09.032314Z","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-01T15:43:09.032407Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33: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.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33: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-01T15:43:09.032475Z","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-01T15:43:09.032487Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-01T15:43:09.032503Z","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-01T15:43:09.033115Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]} {"level":"info","ts":"2026-04-01T15:43:09.033186Z","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.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33: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-01T15:43:09.037435Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.046487ms"} {"level":"info","ts":"2026-04-01T15:43:09.042503Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"} {"level":"info","ts":"2026-04-01T15:43:09.042592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T15:43:09.042624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"} {"level":"info","ts":"2026-04-01T15:43:09.042638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T15:43:09.04265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"} {"level":"info","ts":"2026-04-01T15:43:09.042691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"warn","ts":"2026-04-01T15:43:09.050364Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T15:43:09.05227Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T15:43:09.053814Z","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-01T15:43:09.055879Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T15:43:09.056084Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T15:43:09.056644Z","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-01T15:43:09.056735Z","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-01T15:43:09.057595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"} {"level":"info","ts":"2026-04-01T15:43:09.057718Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]} {"level":"info","ts":"2026-04-01T15:43:09.056749Z","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-01T15:43:09.060198Z","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-01T15:43:09.060339Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-01T15:43:09.060384Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"} {"level":"info","ts":"2026-04-01T15:43:09.060808Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T15:43:09.060874Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T15:43:09.543122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T15:43:09.543285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T15:43:09.54338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"} {"level":"info","ts":"2026-04-01T15:43:09.543437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"} {"level":"info","ts":"2026-04-01T15:43:09.543496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-01T15:43:09.543552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"} {"level":"info","ts":"2026-04-01T15:43:09.543612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"} {"level":"info","ts":"2026-04-01T15:43:09.544876Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:43:09.545865Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T15:43:09.545925Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T15:43:09.545981Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T15:43:09.546382Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T15:43:09.546468Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T15:43:09.546566Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:43:09.547882Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:43:09.547948Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T15:43:09.549136Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T15:43:09.554119Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"} {"level":"warn","ts":"2026-04-01T15:43:39.233363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.896029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:43:39.233609Z","caller":"traceutil/trace.go:171","msg":"trace[1545484681] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.074290557s","start":"2026-04-01T15:43:38.159268Z","end":"2026-04-01T15:43:39.233559Z","steps":["trace[1545484681] 'process raft request' (duration: 502.494026ms)","trace[1545484681] 'compare' (duration: 570.731615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:43:39.233747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:38.159254Z","time spent":"1.07442801s","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:43:39.233833Z","caller":"traceutil/trace.go:171","msg":"trace[1059795183] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.058647581s","start":"2026-04-01T15:43:38.175132Z","end":"2026-04-01T15:43:39.23378Z","steps":["trace[1059795183] 'process raft request' (duration: 1.058368714s)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:43:39.233887Z","caller":"traceutil/trace.go:171","msg":"trace[80918333] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:518; }","duration":"1.03028091s","start":"2026-04-01T15:43:38.203545Z","end":"2026-04-01T15:43:39.233826Z","steps":["trace[80918333] 'read index received' (duration: 458.285795ms)","trace[80918333] 'applied index is now lower than readState.Index' (duration: 571.993805ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:43:39.234026Z","caller":"traceutil/trace.go:171","msg":"trace[329181741] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.008948428s","start":"2026-04-01T15:43:38.225063Z","end":"2026-04-01T15:43:39.234012Z","steps":["trace[329181741] 'process raft request' (duration: 1.00864987s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:39.234082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:38.175114Z","time spent":"1.058825075s","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:43:39.234118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:38.22503Z","time spent":"1.00903766s","remote":"127.0.0.1:45598","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-01T15:43:39.234155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027395671s","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-01T15:43:39.234186Z","caller":"traceutil/trace.go:171","msg":"trace[1392528981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"1.027457612s","start":"2026-04-01T15:43:38.20672Z","end":"2026-04-01T15:43:39.234177Z","steps":["trace[1392528981] 'agreement among raft nodes before linearized reading' (duration: 1.027315698s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:39.234205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:38.206711Z","time spent":"1.027489583s","remote":"127.0.0.1:45836","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-01T15:43:39.234292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.52176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:39.234324Z","caller":"traceutil/trace.go:171","msg":"trace[1082436679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"949.596212ms","start":"2026-04-01T15:43:38.284718Z","end":"2026-04-01T15:43:39.234315Z","steps":["trace[1082436679] 'agreement among raft nodes before linearized reading' (duration: 949.51367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:39.234337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030760981s","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-01T15:43:39.234419Z","caller":"traceutil/trace.go:171","msg":"trace[1570818665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:500; }","duration":"1.030896094s","start":"2026-04-01T15:43:38.203507Z","end":"2026-04-01T15:43:39.234403Z","steps":["trace[1570818665] 'agreement among raft nodes before linearized reading' (duration: 1.03073981s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:39.23446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:38.203481Z","time spent":"1.030968436s","remote":"127.0.0.1:45836","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-01T15:43:39.234535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.170813ms","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-01T15:43:39.234622Z","caller":"traceutil/trace.go:171","msg":"trace[1899837143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"234.330457ms","start":"2026-04-01T15:43:39.00027Z","end":"2026-04-01T15:43:39.234601Z","steps":["trace[1899837143] 'agreement among raft nodes before linearized reading' (duration: 233.970898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:39.234367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:38.284706Z","time spent":"949.656194ms","remote":"127.0.0.1:45538","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-01T15:43:40.784381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155512976635482,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:43:41.191084Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.007095183s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T15:43:41.243073Z","caller":"traceutil/trace.go:171","msg":"trace[1650099503] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.059492622s","start":"2026-04-01T15:43:40.183554Z","end":"2026-04-01T15:43:41.243046Z","steps":["trace[1650099503] 'process raft request' (duration: 1.007988785s)","trace[1650099503] 'compare' (duration: 50.952963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:43:41.243178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:40.183517Z","time spent":"1.059608565s","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T15:43:41.243807Z","caller":"traceutil/trace.go:171","msg":"trace[1031715053] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.047496843s","start":"2026-04-01T15:43:40.196279Z","end":"2026-04-01T15:43:41.243776Z","steps":["trace[1031715053] 'process raft request' (duration: 1.046624022s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:41.24397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:40.196256Z","time spent":"1.047614165s","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:43:41.244184Z","caller":"traceutil/trace.go:171","msg":"trace[1605063726] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:560; }","duration":"960.371751ms","start":"2026-04-01T15:43:40.283782Z","end":"2026-04-01T15:43:41.244154Z","steps":["trace[1605063726] 'read index received' (duration: 907.653705ms)","trace[1605063726] 'applied index is now lower than readState.Index' (duration: 52.717066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:43:41.244312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.517694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:41.244346Z","caller":"traceutil/trace.go:171","msg":"trace[527640551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"960.564406ms","start":"2026-04-01T15:43:40.283769Z","end":"2026-04-01T15:43:41.244333Z","steps":["trace[527640551] 'agreement among raft nodes before linearized reading' (duration: 960.495184ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:43:41.244371Z","caller":"traceutil/trace.go:171","msg":"trace[138540580] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"905.987035ms","start":"2026-04-01T15:43:40.338373Z","end":"2026-04-01T15:43:41.24436Z","steps":["trace[138540580] 'process raft request' (duration: 904.805287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:41.244376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:40.283699Z","time spent":"960.670248ms","remote":"127.0.0.1:45538","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-01T15:43:41.244434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:40.33834Z","time spent":"906.061287ms","remote":"127.0.0.1:45598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:43:45.275227Z","caller":"traceutil/trace.go:171","msg":"trace[44155347] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"208.897116ms","start":"2026-04-01T15:43:45.066282Z","end":"2026-04-01T15:43:45.275179Z","steps":["trace[44155347] 'process raft request' (duration: 203.879015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:45.435338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.613876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:43:45.43554Z","caller":"traceutil/trace.go:171","msg":"trace[356745282] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"149.061729ms","start":"2026-04-01T15:43:45.286459Z","end":"2026-04-01T15:43:45.435521Z","steps":["trace[356745282] 'read index received' (duration: 28.111335ms)","trace[356745282] 'applied index is now lower than readState.Index' (duration: 120.949044ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:43:45.435604Z","caller":"traceutil/trace.go:171","msg":"trace[707831242] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"151.725493ms","start":"2026-04-01T15:43:45.283842Z","end":"2026-04-01T15:43:45.435567Z","steps":["trace[707831242] 'process raft request' (duration: 30.779179ms)","trace[707831242] 'compare' (duration: 120.37208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:43:45.435709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.201412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:45.435817Z","caller":"traceutil/trace.go:171","msg":"trace[1624729575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"149.360756ms","start":"2026-04-01T15:43:45.286427Z","end":"2026-04-01T15:43:45.435788Z","steps":["trace[1624729575] 'agreement among raft nodes before linearized reading' (duration: 149.185812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:45.997349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.756206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:43:45.997506Z","caller":"traceutil/trace.go:171","msg":"trace[649489300] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"561.896182ms","start":"2026-04-01T15:43:45.435594Z","end":"2026-04-01T15:43:45.99749Z","steps":["trace[649489300] 'read index received' (duration: 412.011353ms)","trace[649489300] 'applied index is now lower than readState.Index' (duration: 149.883939ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:43:45.997817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.480229ms","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-01T15:43:45.997817Z","caller":"traceutil/trace.go:171","msg":"trace[1943675709] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"666.30229ms","start":"2026-04-01T15:43:45.33145Z","end":"2026-04-01T15:43:45.997753Z","steps":["trace[1943675709] 'process raft request' (duration: 665.990992ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:43:45.997903Z","caller":"traceutil/trace.go:171","msg":"trace[1242926857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"702.589211ms","start":"2026-04-01T15:43:45.29527Z","end":"2026-04-01T15:43:45.997859Z","steps":["trace[1242926857] 'agreement among raft nodes before linearized reading' (duration: 702.394017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:45.997986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.991821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T15:43:45.997855Z","caller":"traceutil/trace.go:171","msg":"trace[1125086085] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"701.554276ms","start":"2026-04-01T15:43:45.296226Z","end":"2026-04-01T15:43:45.99778Z","steps":["trace[1125086085] 'process raft request' (duration: 551.278698ms)","trace[1125086085] 'compare' (duration: 149.609542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:43:45.998042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:45.295249Z","time spent":"702.756815ms","remote":"127.0.0.1:45836","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-01T15:43:45.998056Z","caller":"traceutil/trace.go:171","msg":"trace[1251451146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"708.095084ms","start":"2026-04-01T15:43:45.289943Z","end":"2026-04-01T15:43:45.998038Z","steps":["trace[1251451146] 'agreement among raft nodes before linearized reading' (duration: 707.824737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:45.998102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:45.289928Z","time spent":"708.163105ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T15:43:45.998143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:45.331432Z","time spent":"666.530455ms","remote":"127.0.0.1:45924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T15:43:45.998183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:45.296199Z","time spent":"701.891415ms","remote":"127.0.0.1:45748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:43:46.837829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.880326ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:46.837993Z","caller":"traceutil/trace.go:171","msg":"trace[597943209] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:574; }","duration":"194.05617ms","start":"2026-04-01T15:43:46.643915Z","end":"2026-04-01T15:43:46.837971Z","steps":["trace[597943209] 'range keys from in-memory index tree' (duration: 193.864286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:46.83803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.981404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:43:46.838216Z","caller":"traceutil/trace.go:171","msg":"trace[229456819] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:595; }","duration":"832.713706ms","start":"2026-04-01T15:43:46.005487Z","end":"2026-04-01T15:43:46.838201Z","steps":["trace[229456819] 'read index received' (duration: 16.414254ms)","trace[229456819] 'applied index is now lower than readState.Index' (duration: 816.298581ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:43:46.838224Z","caller":"traceutil/trace.go:171","msg":"trace[469729361] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"834.086339ms","start":"2026-04-01T15:43:46.004117Z","end":"2026-04-01T15:43:46.838204Z","steps":["trace[469729361] 'process raft request' (duration: 256.851028ms)","trace[469729361] 'compare' (duration: 576.753129ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:43:46.83826Z","caller":"traceutil/trace.go:171","msg":"trace[1661398305] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"833.968395ms","start":"2026-04-01T15:43:46.004262Z","end":"2026-04-01T15:43:46.838231Z","steps":["trace[1661398305] 'process raft request' (duration: 833.855173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:46.838317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.56761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:43:46.838312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.819158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:46.838346Z","caller":"traceutil/trace.go:171","msg":"trace[1452439549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"555.630042ms","start":"2026-04-01T15:43:46.282705Z","end":"2026-04-01T15:43:46.838335Z","steps":["trace[1452439549] 'agreement among raft nodes before linearized reading' (duration: 555.586281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:46.838408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:46.004246Z","time spent":"834.072148ms","remote":"127.0.0.1:45836","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-01T15:43:46.838424Z","caller":"traceutil/trace.go:171","msg":"trace[1708218107] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:576; }","duration":"832.89402ms","start":"2026-04-01T15:43:46.005456Z","end":"2026-04-01T15:43:46.83835Z","steps":["trace[1708218107] 'agreement among raft nodes before linearized reading' (duration: 832.807648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:46.838442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:46.282685Z","time spent":"555.749565ms","remote":"127.0.0.1:45538","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-01T15:43:46.838424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:46.004051Z","time spent":"834.222361ms","remote":"127.0.0.1:45836","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-01T15:43:46.838477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:46.005445Z","time spent":"833.017983ms","remote":"127.0.0.1:45940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"info","ts":"2026-04-01T15:43:48.717384Z","caller":"traceutil/trace.go:171","msg":"trace[1122138899] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"323.19441ms","start":"2026-04-01T15:43:48.39417Z","end":"2026-04-01T15:43:48.717364Z","steps":["trace[1122138899] 'read index received' (duration: 310.050334ms)","trace[1122138899] 'applied index is now lower than readState.Index' (duration: 13.143366ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:43:48.717521Z","caller":"traceutil/trace.go:171","msg":"trace[90399105] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"327.547414ms","start":"2026-04-01T15:43:48.389958Z","end":"2026-04-01T15:43:48.717505Z","steps":["trace[90399105] 'process raft request' (duration: 314.317686ms)","trace[90399105] 'compare' (duration: 12.861519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:43:48.717634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:48.389895Z","time spent":"327.692768ms","remote":"127.0.0.1:45808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T15:43:48.717683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.363824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.717734Z","caller":"traceutil/trace.go:171","msg":"trace[713865572] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"323.458886ms","start":"2026-04-01T15:43:48.394264Z","end":"2026-04-01T15:43:48.717723Z","steps":["trace[713865572] 'agreement among raft nodes before linearized reading' (duration: 323.363914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.717763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:48.39424Z","time spent":"323.517627ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-01T15:43:48.717878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.323205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.717904Z","caller":"traceutil/trace.go:171","msg":"trace[1669115557] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:588; }","duration":"119.369207ms","start":"2026-04-01T15:43:48.598529Z","end":"2026-04-01T15:43:48.717898Z","steps":["trace[1669115557] 'agreement among raft nodes before linearized reading' (duration: 119.331886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.717971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.248153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:43:48.718083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.050244ms","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-01T15:43:48.718119Z","caller":"traceutil/trace.go:171","msg":"trace[494838279] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"158.116236ms","start":"2026-04-01T15:43:48.559996Z","end":"2026-04-01T15:43:48.718112Z","steps":["trace[494838279] 'agreement among raft nodes before linearized reading' (duration: 158.071745ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:43:48.718099Z","caller":"traceutil/trace.go:171","msg":"trace[764258026] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:588; }","duration":"119.500009ms","start":"2026-04-01T15:43:48.598584Z","end":"2026-04-01T15:43:48.718084Z","steps":["trace[764258026] 'agreement among raft nodes before linearized reading' (duration: 119.218912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.718223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.214848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.718249Z","caller":"traceutil/trace.go:171","msg":"trace[411476718] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"158.26694ms","start":"2026-04-01T15:43:48.559978Z","end":"2026-04-01T15:43:48.718245Z","steps":["trace[411476718] 'agreement among raft nodes before linearized reading' (duration: 158.226439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.718353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.26703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.718376Z","caller":"traceutil/trace.go:171","msg":"trace[599894724] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:588; }","duration":"188.305101ms","start":"2026-04-01T15:43:48.530066Z","end":"2026-04-01T15:43:48.718371Z","steps":["trace[599894724] 'agreement among raft nodes before linearized reading' (duration: 188.24474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.718487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.83644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.718553Z","caller":"traceutil/trace.go:171","msg":"trace[941446218] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"212.915341ms","start":"2026-04-01T15:43:48.505596Z","end":"2026-04-01T15:43:48.718511Z","steps":["trace[941446218] 'agreement among raft nodes before linearized reading' (duration: 212.84955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.718605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.334576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.718655Z","caller":"traceutil/trace.go:171","msg":"trace[109022690] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:588; }","duration":"119.458179ms","start":"2026-04-01T15:43:48.599189Z","end":"2026-04-01T15:43:48.718647Z","steps":["trace[109022690] 'agreement among raft nodes before linearized reading' (duration: 119.362766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.718704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.214373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:43:48.718751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.273234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.71875Z","caller":"traceutil/trace.go:171","msg":"trace[2124626132] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:588; }","duration":"189.299595ms","start":"2026-04-01T15:43:48.529444Z","end":"2026-04-01T15:43:48.718744Z","steps":["trace[2124626132] 'agreement among raft nodes before linearized reading' (duration: 189.237503ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:43:48.718772Z","caller":"traceutil/trace.go:171","msg":"trace[1489460441] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:588; }","duration":"189.321025ms","start":"2026-04-01T15:43:48.529445Z","end":"2026-04-01T15:43:48.718766Z","steps":["trace[1489460441] 'agreement among raft nodes before linearized reading' (duration: 189.294695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.718005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.430718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:43:48.718832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.988023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.718873Z","caller":"traceutil/trace.go:171","msg":"trace[820595194] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"213.058515ms","start":"2026-04-01T15:43:48.505808Z","end":"2026-04-01T15:43:48.718866Z","steps":["trace[820595194] 'agreement among raft nodes before linearized reading' (duration: 213.008454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.718739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.534022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.719061Z","caller":"traceutil/trace.go:171","msg":"trace[1507881277] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"324.809458ms","start":"2026-04-01T15:43:48.394163Z","end":"2026-04-01T15:43:48.718972Z","steps":["trace[1507881277] 'agreement among raft nodes before linearized reading' (duration: 324.346577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.718722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.800779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:43:48.720018Z","caller":"traceutil/trace.go:171","msg":"trace[2047987609] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"214.0976ms","start":"2026-04-01T15:43:48.505912Z","end":"2026-04-01T15:43:48.720009Z","steps":["trace[2047987609] 'agreement among raft nodes before linearized reading' (duration: 212.791429ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:43:48.718855Z","caller":"traceutil/trace.go:171","msg":"trace[1686466438] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"135.293368ms","start":"2026-04-01T15:43:48.583552Z","end":"2026-04-01T15:43:48.718845Z","steps":["trace[1686466438] 'agreement among raft nodes before linearized reading' (duration: 134.434928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:43:48.718056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.967431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:43:48.718034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.9348ms","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":"warn","ts":"2026-04-01T15:43:48.719181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:43:48.394146Z","time spent":"324.958413ms","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-01T15:43:48.720509Z","caller":"traceutil/trace.go:171","msg":"trace[557761360] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"137.43761ms","start":"2026-04-01T15:43:48.583061Z","end":"2026-04-01T15:43:48.720499Z","steps":["trace[557761360] 'agreement among raft nodes before linearized reading' (duration: 134.983041ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:43:48.720658Z","caller":"traceutil/trace.go:171","msg":"trace[210446367] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"137.585434ms","start":"2026-04-01T15:43:48.583065Z","end":"2026-04-01T15:43:48.720651Z","steps":["trace[210446367] 'agreement among raft nodes before linearized reading' (duration: 134.95344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:02.950653Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155512976635945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:44:03.451016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155512976635945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:44:03.601181Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.29599938s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T15:44:03.601725Z","caller":"traceutil/trace.go:171","msg":"trace[154573829] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"1.151289424s","start":"2026-04-01T15:44:02.450408Z","end":"2026-04-01T15:44:03.601698Z","steps":["trace[154573829] 'read index received' (duration: 1.151071289s)","trace[154573829] 'applied index is now lower than readState.Index' (duration: 216.955µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:44:03.60183Z","caller":"traceutil/trace.go:171","msg":"trace[1223198131] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"1.296707297s","start":"2026-04-01T15:44:02.305094Z","end":"2026-04-01T15:44:03.601801Z","steps":["trace[1223198131] 'process raft request' (duration: 1.296457731s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:03.601979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:02.30508Z","time spent":"1.296820009s","remote":"127.0.0.1:45748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:44:03.602002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.75922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:44:03.602091Z","caller":"traceutil/trace.go:171","msg":"trace[218273635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"317.902833ms","start":"2026-04-01T15:44:03.284164Z","end":"2026-04-01T15:44:03.602067Z","steps":["trace[218273635] 'agreement among raft nodes before linearized reading' (duration: 317.740369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:03.602146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:03.284141Z","time spent":"317.996896ms","remote":"127.0.0.1:45538","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-01T15:44:03.602117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.007313ms","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-01T15:44:03.602245Z","caller":"traceutil/trace.go:171","msg":"trace[1418939073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:740; }","duration":"686.152716ms","start":"2026-04-01T15:44:02.916041Z","end":"2026-04-01T15:44:03.602194Z","steps":["trace[1418939073] 'agreement among raft nodes before linearized reading' (duration: 685.829388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:03.602275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.137314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-01T15:44:03.602285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.716861ms","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-01T15:44:03.602371Z","caller":"traceutil/trace.go:171","msg":"trace[487469305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"571.281858ms","start":"2026-04-01T15:44:03.031063Z","end":"2026-04-01T15:44:03.602345Z","steps":["trace[487469305] 'agreement among raft nodes before linearized reading' (duration: 570.918249ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:03.602382Z","caller":"traceutil/trace.go:171","msg":"trace[449381765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"684.872315ms","start":"2026-04-01T15:44:02.917487Z","end":"2026-04-01T15:44:03.602359Z","steps":["trace[449381765] 'agreement among raft nodes before linearized reading' (duration: 684.511056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:03.602427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:03.031019Z","time spent":"571.39857ms","remote":"127.0.0.1:45836","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-01T15:44:03.60243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:02.917466Z","time spent":"684.955398ms","remote":"127.0.0.1:45836","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-01T15:44:03.602346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:02.916019Z","time spent":"686.308149ms","remote":"127.0.0.1:45836","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-01T15:44:03.602687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152286579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T15:44:03.602734Z","caller":"traceutil/trace.go:171","msg":"trace[898426948] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:740; }","duration":"1.15236218s","start":"2026-04-01T15:44:02.45036Z","end":"2026-04-01T15:44:03.602722Z","steps":["trace[898426948] 'agreement among raft nodes before linearized reading' (duration: 1.151463618s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:03.602762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:02.450345Z","time spent":"1.152410742s","remote":"127.0.0.1:45584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "} {"level":"info","ts":"2026-04-01T15:44:37.190409Z","caller":"traceutil/trace.go:171","msg":"trace[755689472] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"176.177833ms","start":"2026-04-01T15:44:37.014206Z","end":"2026-04-01T15:44:37.190384Z","steps":["trace[755689472] 'process raft request' (duration: 176.038689ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:37.463668Z","caller":"traceutil/trace.go:171","msg":"trace[1629516500] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"180.568016ms","start":"2026-04-01T15:44:37.283081Z","end":"2026-04-01T15:44:37.463649Z","steps":["trace[1629516500] 'read index received' (duration: 180.386282ms)","trace[1629516500] 'applied index is now lower than readState.Index' (duration: 181.094µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:44:37.463771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.677758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:44:37.463794Z","caller":"traceutil/trace.go:171","msg":"trace[518741724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"180.779731ms","start":"2026-04-01T15:44:37.283007Z","end":"2026-04-01T15:44:37.463787Z","steps":["trace[518741724] 'agreement among raft nodes before linearized reading' (duration: 180.72047ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:37.463766Z","caller":"traceutil/trace.go:171","msg":"trace[1532162469] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"264.055453ms","start":"2026-04-01T15:44:37.19968Z","end":"2026-04-01T15:44:37.463735Z","steps":["trace[1532162469] 'process raft request' (duration: 263.850158ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:38.637248Z","caller":"traceutil/trace.go:171","msg":"trace[1386748586] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"111.560153ms","start":"2026-04-01T15:44:38.525663Z","end":"2026-04-01T15:44:38.637223Z","steps":["trace[1386748586] 'process raft request' (duration: 84.680736ms)","trace[1386748586] 'compare' (duration: 26.534358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:44:40.265196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.28101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T15:44:40.26528Z","caller":"traceutil/trace.go:171","msg":"trace[186125609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"403.367602ms","start":"2026-04-01T15:44:39.86189Z","end":"2026-04-01T15:44:40.265257Z","steps":["trace[186125609] 'range keys from in-memory index tree' (duration: 403.129587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:40.265325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:39.861837Z","time spent":"403.472735ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T15:44:40.265537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.174108ms","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-01T15:44:40.265565Z","caller":"traceutil/trace.go:171","msg":"trace[1975697987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"403.220699ms","start":"2026-04-01T15:44:39.862335Z","end":"2026-04-01T15:44:40.265556Z","steps":["trace[1975697987] 'range keys from in-memory index tree' (duration: 403.095866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:40.265591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:39.862327Z","time spent":"403.2579ms","remote":"127.0.0.1:45836","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-01T15:44:40.265208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.825802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:44:40.265724Z","caller":"traceutil/trace.go:171","msg":"trace[964651067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"982.376745ms","start":"2026-04-01T15:44:39.283334Z","end":"2026-04-01T15:44:40.265711Z","steps":["trace[964651067] 'range keys from in-memory index tree' (duration: 981.75991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:40.265733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.148755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-01T15:44:40.265762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:39.283318Z","time spent":"982.435516ms","remote":"127.0.0.1:45538","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-01T15:44:40.265782Z","caller":"traceutil/trace.go:171","msg":"trace[2103087836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"378.206996ms","start":"2026-04-01T15:44:39.887557Z","end":"2026-04-01T15:44:40.265764Z","steps":["trace[2103087836] 'range keys from in-memory index tree' (duration: 378.052873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:40.265828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:39.887548Z","time spent":"378.271569ms","remote":"127.0.0.1:45836","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-01T15:44:40.265981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.088407ms","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-01T15:44:40.266018Z","caller":"traceutil/trace.go:171","msg":"trace[1657767198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"379.399925ms","start":"2026-04-01T15:44:39.886609Z","end":"2026-04-01T15:44:40.266009Z","steps":["trace[1657767198] 'range keys from in-memory index tree' (duration: 378.968175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:40.266045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:39.886598Z","time spent":"379.440936ms","remote":"127.0.0.1:45836","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-01T15:44:40.843963Z","caller":"traceutil/trace.go:171","msg":"trace[1012823937] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"574.442414ms","start":"2026-04-01T15:44:40.269499Z","end":"2026-04-01T15:44:40.843941Z","steps":["trace[1012823937] 'process raft request' (duration: 574.30412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:40.8441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:40.269473Z","time spent":"574.552607ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:44:40.844797Z","caller":"traceutil/trace.go:171","msg":"trace[220589672] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"574.764181ms","start":"2026-04-01T15:44:40.270021Z","end":"2026-04-01T15:44:40.844785Z","steps":["trace[220589672] 'process raft request' (duration: 574.69996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:40.844879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:40.270011Z","time spent":"574.830103ms","remote":"127.0.0.1:45836","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-01T15:44:40.845018Z","caller":"traceutil/trace.go:171","msg":"trace[869647550] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"575.296484ms","start":"2026-04-01T15:44:40.269711Z","end":"2026-04-01T15:44:40.845008Z","steps":["trace[869647550] 'process raft request' (duration: 574.955266ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:40.845029Z","caller":"traceutil/trace.go:171","msg":"trace[1911054857] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"574.856614ms","start":"2026-04-01T15:44:40.270151Z","end":"2026-04-01T15:44:40.845007Z","steps":["trace[1911054857] 'process raft request' (duration: 574.602538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:40.845081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:40.269704Z","time spent":"575.346965ms","remote":"127.0.0.1:45836","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-01T15:44:40.845184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:40.27013Z","time spent":"574.980106ms","remote":"127.0.0.1:45836","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-01T15:44:40.845227Z","caller":"traceutil/trace.go:171","msg":"trace[235087144] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:993; }","duration":"395.562678ms","start":"2026-04-01T15:44:40.449654Z","end":"2026-04-01T15:44:40.845216Z","steps":["trace[235087144] 'read index received' (duration: 394.196665ms)","trace[235087144] 'applied index is now lower than readState.Index' (duration: 1.365203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:44:40.845352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.701791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T15:44:40.84538Z","caller":"traceutil/trace.go:171","msg":"trace[223779824] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"203.405567ms","start":"2026-04-01T15:44:40.641962Z","end":"2026-04-01T15:44:40.845368Z","steps":["trace[223779824] 'process raft request' (duration: 202.972647ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:40.845387Z","caller":"traceutil/trace.go:171","msg":"trace[608222537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"395.771412ms","start":"2026-04-01T15:44:40.449606Z","end":"2026-04-01T15:44:40.845377Z","steps":["trace[608222537] 'agreement among raft nodes before linearized reading' (duration: 395.65331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:44:40.845423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:44:40.449586Z","time spent":"395.829733ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-01T15:44:42.997132Z","caller":"traceutil/trace.go:171","msg":"trace[970183434] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"139.582226ms","start":"2026-04-01T15:44:42.857531Z","end":"2026-04-01T15:44:42.997113Z","steps":["trace[970183434] 'process raft request' (duration: 139.538775ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:42.997287Z","caller":"traceutil/trace.go:171","msg":"trace[1500270557] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"141.634814ms","start":"2026-04-01T15:44:42.855634Z","end":"2026-04-01T15:44:42.997269Z","steps":["trace[1500270557] 'process raft request' (duration: 125.542343ms)","trace[1500270557] 'compare' (duration: 15.812135ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:44:43.013899Z","caller":"traceutil/trace.go:171","msg":"trace[1800240685] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"143.914578ms","start":"2026-04-01T15:44:42.86997Z","end":"2026-04-01T15:44:43.013884Z","steps":["trace[1800240685] 'process raft request' (duration: 143.762104ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:43.013914Z","caller":"traceutil/trace.go:171","msg":"trace[554600623] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"143.260512ms","start":"2026-04-01T15:44:42.870639Z","end":"2026-04-01T15:44:43.0139Z","steps":["trace[554600623] 'process raft request' (duration: 143.193831ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:59.190153Z","caller":"traceutil/trace.go:171","msg":"trace[1873529871] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"109.702398ms","start":"2026-04-01T15:44:59.080426Z","end":"2026-04-01T15:44:59.190128Z","steps":["trace[1873529871] 'process raft request' (duration: 108.736245ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:59.190376Z","caller":"traceutil/trace.go:171","msg":"trace[348134040] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1060; }","duration":"104.195307ms","start":"2026-04-01T15:44:59.086159Z","end":"2026-04-01T15:44:59.190355Z","steps":["trace[348134040] 'read index received' (duration: 25.968115ms)","trace[348134040] 'applied index is now lower than readState.Index' (duration: 78.226212ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:44:59.19055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.371251ms","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-01T15:44:59.19054Z","caller":"traceutil/trace.go:171","msg":"trace[1090678592] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"107.217919ms","start":"2026-04-01T15:44:59.083303Z","end":"2026-04-01T15:44:59.190521Z","steps":["trace[1090678592] 'process raft request' (duration: 106.787139ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:44:59.190587Z","caller":"traceutil/trace.go:171","msg":"trace[605542472] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1022; }","duration":"104.437784ms","start":"2026-04-01T15:44:59.08614Z","end":"2026-04-01T15:44:59.190578Z","steps":["trace[605542472] 'agreement among raft nodes before linearized reading' (duration: 104.29765ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:01.038475Z","caller":"traceutil/trace.go:171","msg":"trace[1417170883] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"107.941057ms","start":"2026-04-01T15:45:00.930508Z","end":"2026-04-01T15:45:01.038449Z","steps":["trace[1417170883] 'process raft request' (duration: 107.800683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:01.796108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.238159ms","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-01T15:45:01.796192Z","caller":"traceutil/trace.go:171","msg":"trace[1395647404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"566.368423ms","start":"2026-04-01T15:45:01.229805Z","end":"2026-04-01T15:45:01.796173Z","steps":["trace[1395647404] 'range keys from in-memory index tree' (duration: 566.049245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:01.796236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:01.229788Z","time spent":"566.438144ms","remote":"127.0.0.1:45836","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-01T15:45:01.796227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.997468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:45:01.796302Z","caller":"traceutil/trace.go:171","msg":"trace[995316797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"513.122431ms","start":"2026-04-01T15:45:01.28316Z","end":"2026-04-01T15:45:01.796283Z","steps":["trace[995316797] 'range keys from in-memory index tree' (duration: 512.920897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:01.796312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.439202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-01T15:45:01.796339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:01.28314Z","time spent":"513.191442ms","remote":"127.0.0.1:45538","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-01T15:45:01.796379Z","caller":"traceutil/trace.go:171","msg":"trace[934619961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"601.547445ms","start":"2026-04-01T15:45:01.194814Z","end":"2026-04-01T15:45:01.796361Z","steps":["trace[934619961] 'range keys from in-memory index tree' (duration: 601.281199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:01.796419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:01.194794Z","time spent":"601.615617ms","remote":"127.0.0.1:45836","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-01T15:45:01.796527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.669238ms","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-01T15:45:01.796559Z","caller":"traceutil/trace.go:171","msg":"trace[584467173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"601.73583ms","start":"2026-04-01T15:45:01.194814Z","end":"2026-04-01T15:45:01.79655Z","steps":["trace[584467173] 'range keys from in-memory index tree' (duration: 601.527075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:01.796588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:01.194794Z","time spent":"601.787071ms","remote":"127.0.0.1:45836","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-01T15:45:02.784038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155512976636595,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:45:02.954521Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.153486997s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T15:45:02.954919Z","caller":"traceutil/trace.go:171","msg":"trace[995615091] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"1.153972729s","start":"2026-04-01T15:45:01.800923Z","end":"2026-04-01T15:45:02.954896Z","steps":["trace[995615091] 'process raft request' (duration: 1.153819695s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:02.955102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:01.800899Z","time spent":"1.154084811s","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:45:03.348844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.172165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:45:03.349078Z","caller":"traceutil/trace.go:171","msg":"trace[313763385] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1066; }","duration":"1.065627547s","start":"2026-04-01T15:45:02.283406Z","end":"2026-04-01T15:45:03.349033Z","steps":["trace[313763385] 'read index received' (duration: 671.411751ms)","trace[313763385] 'applied index is now lower than readState.Index' (duration: 394.214696ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:45:03.349205Z","caller":"traceutil/trace.go:171","msg":"trace[452672098] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"1.547862527s","start":"2026-04-01T15:45:01.801304Z","end":"2026-04-01T15:45:03.349167Z","steps":["trace[452672098] 'process raft request' (duration: 1.400291852s)","trace[452672098] 'compare' (duration: 146.830327ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:45:03.349247Z","caller":"traceutil/trace.go:171","msg":"trace[32520028] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.54761995s","start":"2026-04-01T15:45:01.801609Z","end":"2026-04-01T15:45:03.349229Z","steps":["trace[32520028] 'process raft request' (duration: 1.547318893s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.349383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:01.801277Z","time spent":"1.54801169s","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:45:03.349394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065991055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:45:03.349394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:01.8016Z","time spent":"1.547752394s","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:45:03.349444Z","caller":"traceutil/trace.go:171","msg":"trace[1633414973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"1.066075827s","start":"2026-04-01T15:45:02.283355Z","end":"2026-04-01T15:45:03.349431Z","steps":["trace[1633414973] 'agreement among raft nodes before linearized reading' (duration: 1.065958174s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.349563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:02.283335Z","time spent":"1.06621496s","remote":"127.0.0.1:45538","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-01T15:45:03.777158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.858361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:45:03.777356Z","caller":"traceutil/trace.go:171","msg":"trace[1014319269] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1069; }","duration":"428.179599ms","start":"2026-04-01T15:45:03.349159Z","end":"2026-04-01T15:45:03.777338Z","steps":["trace[1014319269] 'read index received' (duration: 133.057841ms)","trace[1014319269] 'applied index is now lower than readState.Index' (duration: 295.120698ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:03.777466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141669476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:45:03.7775Z","caller":"traceutil/trace.go:171","msg":"trace[2045208941] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1031; }","duration":"1.141741767s","start":"2026-04-01T15:45:02.635749Z","end":"2026-04-01T15:45:03.777491Z","steps":["trace[2045208941] 'agreement among raft nodes before linearized reading' (duration: 1.141685096s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.777624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:02.635729Z","time spent":"1.141880881s","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-01T15:45:03.777615Z","caller":"traceutil/trace.go:171","msg":"trace[1306759219] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"796.875501ms","start":"2026-04-01T15:45:02.98071Z","end":"2026-04-01T15:45:03.777586Z","steps":["trace[1306759219] 'process raft request' (duration: 501.504157ms)","trace[1306759219] 'compare' (duration: 294.728278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:03.777763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:02.980687Z","time spent":"796.994814ms","remote":"127.0.0.1:45836","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-01T15:45:03.777889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272201528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:45:03.777927Z","caller":"traceutil/trace.go:171","msg":"trace[1988288233] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1031; }","duration":"1.272273619s","start":"2026-04-01T15:45:02.505643Z","end":"2026-04-01T15:45:03.777916Z","steps":["trace[1988288233] 'agreement among raft nodes before linearized reading' (duration: 1.272218248s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.777912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.07715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:45:03.777959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:02.505625Z","time spent":"1.27232711s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-01T15:45:03.77797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483675475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:45:03.778005Z","caller":"traceutil/trace.go:171","msg":"trace[1679526797] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1031; }","duration":"581.209813ms","start":"2026-04-01T15:45:03.19677Z","end":"2026-04-01T15:45:03.77798Z","steps":["trace[1679526797] 'agreement among raft nodes before linearized reading' (duration: 581.08821ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:03.778025Z","caller":"traceutil/trace.go:171","msg":"trace[449291116] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1031; }","duration":"1.483755398s","start":"2026-04-01T15:45:02.294255Z","end":"2026-04-01T15:45:03.77801Z","steps":["trace[449291116] 'agreement among raft nodes before linearized reading' (duration: 1.483639235s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.778066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:02.294245Z","time spent":"1.483808839s","remote":"127.0.0.1:46090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-01T15:45:03.778119Z","caller":"traceutil/trace.go:171","msg":"trace[607612455] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"735.020156ms","start":"2026-04-01T15:45:03.043084Z","end":"2026-04-01T15:45:03.778104Z","steps":["trace[607612455] 'process raft request' (duration: 734.181796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.77814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325681304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T15:45:03.778178Z","caller":"traceutil/trace.go:171","msg":"trace[179133059] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:1031; }","duration":"1.325750255s","start":"2026-04-01T15:45:02.452418Z","end":"2026-04-01T15:45:03.778168Z","steps":["trace[179133059] 'agreement among raft nodes before linearized reading' (duration: 1.325621872s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.778214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:02.452399Z","time spent":"1.325808836s","remote":"127.0.0.1:45584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "} {"level":"warn","ts":"2026-04-01T15:45:03.778236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:03.04306Z","time spent":"735.106598ms","remote":"127.0.0.1:45836","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-01T15:45:03.778066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:03.196747Z","time spent":"581.302006ms","remote":"127.0.0.1:34984","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-01T15:45:03.77836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.057412ms","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-01T15:45:03.778123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.548004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:45:03.778411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35549135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:45:03.778418Z","caller":"traceutil/trace.go:171","msg":"trace[1019028664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1031; }","duration":"633.145853ms","start":"2026-04-01T15:45:03.145256Z","end":"2026-04-01T15:45:03.778402Z","steps":["trace[1019028664] 'agreement among raft nodes before linearized reading' (duration: 632.997739ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:03.778444Z","caller":"traceutil/trace.go:171","msg":"trace[1590680867] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1031; }","duration":"1.355557082s","start":"2026-04-01T15:45:02.422878Z","end":"2026-04-01T15:45:03.778435Z","steps":["trace[1590680867] 'agreement among raft nodes before linearized reading' (duration: 1.355505251s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.778452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:03.145242Z","time spent":"633.203105ms","remote":"127.0.0.1:45836","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-01T15:45:03.77844Z","caller":"traceutil/trace.go:171","msg":"trace[854267511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1031; }","duration":"427.895253ms","start":"2026-04-01T15:45:03.35053Z","end":"2026-04-01T15:45:03.778425Z","steps":["trace[854267511] 'agreement among raft nodes before linearized reading' (duration: 427.571235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.778481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:02.42286Z","time spent":"1.355613183s","remote":"127.0.0.1:34892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-01T15:45:03.77852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:03.350491Z","time spent":"428.019166ms","remote":"127.0.0.1:45542","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-01T15:45:03.778617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.509825ms","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-01T15:45:03.778711Z","caller":"traceutil/trace.go:171","msg":"trace[92098087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1031; }","duration":"692.652453ms","start":"2026-04-01T15:45:03.085994Z","end":"2026-04-01T15:45:03.778646Z","steps":["trace[92098087] 'agreement among raft nodes before linearized reading' (duration: 691.363692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:03.778776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:03.08598Z","time spent":"692.784406ms","remote":"127.0.0.1:45836","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-01T15:45:05.266289Z","caller":"traceutil/trace.go:171","msg":"trace[225467558] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"303.715833ms","start":"2026-04-01T15:45:04.962547Z","end":"2026-04-01T15:45:05.266263Z","steps":["trace[225467558] 'process raft request' (duration: 303.507668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:05.26665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:04.962522Z","time spent":"303.852636ms","remote":"127.0.0.1:45836","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-01T15:45:06.523958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.462067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T15:45:06.524063Z","caller":"traceutil/trace.go:171","msg":"trace[322902765] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"1.167647942s","start":"2026-04-01T15:45:05.356395Z","end":"2026-04-01T15:45:06.524043Z","steps":["trace[322902765] 'process raft request' (duration: 519.015691ms)","trace[322902765] 'compare' (duration: 647.991286ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:06.524165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:05.356377Z","time spent":"1.167758614s","remote":"127.0.0.1:45836","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-01T15:45:06.527547Z","caller":"traceutil/trace.go:171","msg":"trace[741130844] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1076; }","duration":"676.671714ms","start":"2026-04-01T15:45:05.850862Z","end":"2026-04-01T15:45:06.527534Z","steps":["trace[741130844] 'read index received' (duration: 24.559021ms)","trace[741130844] 'applied index is now lower than readState.Index' (duration: 652.111573ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:45:06.527599Z","caller":"traceutil/trace.go:171","msg":"trace[1085455985] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"743.172659ms","start":"2026-04-01T15:45:05.784396Z","end":"2026-04-01T15:45:06.527569Z","steps":["trace[1085455985] 'process raft request' (duration: 742.996325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:06.527753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:05.784369Z","time spent":"743.304993ms","remote":"127.0.0.1:45836","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-01T15:45:06.52777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.899839ms","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-01T15:45:06.527844Z","caller":"traceutil/trace.go:171","msg":"trace[1929387301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"677.017102ms","start":"2026-04-01T15:45:05.850814Z","end":"2026-04-01T15:45:06.527831Z","steps":["trace[1929387301] 'agreement among raft nodes before linearized reading' (duration: 676.833108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:06.527949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:05.850792Z","time spent":"677.132956ms","remote":"127.0.0.1:45836","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-01T15:45:06.52791Z","caller":"traceutil/trace.go:171","msg":"trace[1881033417] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"1.170399307s","start":"2026-04-01T15:45:05.357064Z","end":"2026-04-01T15:45:06.527463Z","steps":["trace[1881033417] 'process raft request' (duration: 1.170214472s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:06.528161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:05.35704Z","time spent":"1.171012922s","remote":"127.0.0.1:45836","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-01T15:45:06.682935Z","caller":"traceutil/trace.go:171","msg":"trace[2023657692] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"155.265207ms","start":"2026-04-01T15:45:06.527644Z","end":"2026-04-01T15:45:06.682909Z","steps":["trace[2023657692] 'read index received' (duration: 155.079373ms)","trace[2023657692] 'applied index is now lower than readState.Index' (duration: 184.684µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:45:06.683085Z","caller":"traceutil/trace.go:171","msg":"trace[641621703] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"783.385101ms","start":"2026-04-01T15:45:05.89967Z","end":"2026-04-01T15:45:06.683055Z","steps":["trace[641621703] 'process raft request' (duration: 783.057864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:06.683211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.945862ms","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-01T15:45:06.683262Z","caller":"traceutil/trace.go:171","msg":"trace[722222669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"832.031224ms","start":"2026-04-01T15:45:05.851207Z","end":"2026-04-01T15:45:06.683239Z","steps":["trace[722222669] 'agreement among raft nodes before linearized reading' (duration: 831.800908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:06.683307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:05.851192Z","time spent":"832.102555ms","remote":"127.0.0.1:45836","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-01T15:45:06.683342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:05.899651Z","time spent":"783.524315ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:45:06.708456Z","caller":"traceutil/trace.go:171","msg":"trace[1693507161] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"176.806947ms","start":"2026-04-01T15:45:06.531619Z","end":"2026-04-01T15:45:06.708426Z","steps":["trace[1693507161] 'process raft request' (duration: 176.51756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:07.938452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.243927ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-01T15:45:07.938552Z","caller":"traceutil/trace.go:171","msg":"trace[2028628176] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"196.034553ms","start":"2026-04-01T15:45:07.7425Z","end":"2026-04-01T15:45:07.938535Z","steps":["trace[2028628176] 'read index received' (duration: 21.700954ms)","trace[2028628176] 'applied index is now lower than readState.Index' (duration: 174.331679ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:07.938627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.120434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:45:07.938709Z","caller":"traceutil/trace.go:171","msg":"trace[625404936] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1042; }","duration":"196.246587ms","start":"2026-04-01T15:45:07.742449Z","end":"2026-04-01T15:45:07.938695Z","steps":["trace[625404936] 'agreement among raft nodes before linearized reading' (duration: 196.130705ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:08.657043Z","caller":"traceutil/trace.go:171","msg":"trace[2145829698] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"125.316468ms","start":"2026-04-01T15:45:08.531701Z","end":"2026-04-01T15:45:08.657017Z","steps":["trace[2145829698] 'process raft request' (duration: 125.111483ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:08.757095Z","caller":"traceutil/trace.go:171","msg":"trace[856599400] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"224.266631ms","start":"2026-04-01T15:45:08.532802Z","end":"2026-04-01T15:45:08.757068Z","steps":["trace[856599400] 'process raft request' (duration: 213.165738ms)","trace[856599400] 'compare' (duration: 10.889348ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:45:08.757575Z","caller":"traceutil/trace.go:171","msg":"trace[283829398] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"223.119074ms","start":"2026-04-01T15:45:08.534427Z","end":"2026-04-01T15:45:08.757546Z","steps":["trace[283829398] 'process raft request' (duration: 222.608902ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:17.306729Z","caller":"traceutil/trace.go:171","msg":"trace[870950852] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"140.312174ms","start":"2026-04-01T15:45:17.166392Z","end":"2026-04-01T15:45:17.306704Z","steps":["trace[870950852] 'process raft request' (duration: 140.192211ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:19.382394Z","caller":"traceutil/trace.go:171","msg":"trace[397673132] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"100.178082ms","start":"2026-04-01T15:45:19.282192Z","end":"2026-04-01T15:45:19.38237Z","steps":["trace[397673132] 'read index received' (duration: 98.337709ms)","trace[397673132] 'applied index is now lower than readState.Index' (duration: 1.838943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:19.382533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.332537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:45:19.382569Z","caller":"traceutil/trace.go:171","msg":"trace[2122558661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1132; }","duration":"100.417059ms","start":"2026-04-01T15:45:19.282143Z","end":"2026-04-01T15:45:19.38256Z","steps":["trace[2122558661] 'agreement among raft nodes before linearized reading' (duration: 100.344437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:21.314872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155512976636847,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:45:21.815431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155512976636847,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:45:21.953124Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.214404298s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T15:45:21.953384Z","caller":"traceutil/trace.go:171","msg":"trace[15271416] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"1.139534175s","start":"2026-04-01T15:45:20.813833Z","end":"2026-04-01T15:45:21.953368Z","steps":["trace[15271416] 'read index received' (duration: 1.139387782s)","trace[15271416] 'applied index is now lower than readState.Index' (duration: 145.683µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:21.953538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139258569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T15:45:21.953568Z","caller":"traceutil/trace.go:171","msg":"trace[505707193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1134; }","duration":"1.13931288s","start":"2026-04-01T15:45:20.814246Z","end":"2026-04-01T15:45:21.953559Z","steps":["trace[505707193] 'agreement among raft nodes before linearized reading' (duration: 1.139189827s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:21.953593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:20.814231Z","time spent":"1.139355781s","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T15:45:21.953591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.912093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:45:21.953676Z","caller":"traceutil/trace.go:171","msg":"trace[586364894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1134; }","duration":"670.044477ms","start":"2026-04-01T15:45:21.283611Z","end":"2026-04-01T15:45:21.953656Z","steps":["trace[586364894] 'agreement among raft nodes before linearized reading' (duration: 669.898243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:21.953711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136924523s","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-01T15:45:21.953732Z","caller":"traceutil/trace.go:171","msg":"trace[1575567091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1134; }","duration":"1.136957315s","start":"2026-04-01T15:45:20.816769Z","end":"2026-04-01T15:45:21.953726Z","steps":["trace[1575567091] 'agreement among raft nodes before linearized reading' (duration: 1.136902293s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:21.953728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:21.283595Z","time spent":"670.124638ms","remote":"127.0.0.1:45538","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-01T15:45:21.95371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.113895ms","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-01T15:45:21.953793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139210277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T15:45:21.953796Z","caller":"traceutil/trace.go:171","msg":"trace[1949390682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1134; }","duration":"544.248238ms","start":"2026-04-01T15:45:21.409528Z","end":"2026-04-01T15:45:21.953776Z","steps":["trace[1949390682] 'agreement among raft nodes before linearized reading' (duration: 544.023522ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:21.953817Z","caller":"traceutil/trace.go:171","msg":"trace[684746540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1134; }","duration":"1.139258449s","start":"2026-04-01T15:45:20.814553Z","end":"2026-04-01T15:45:21.953812Z","steps":["trace[684746540] 'agreement among raft nodes before linearized reading' (duration: 1.139210758s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:21.953834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:20.814539Z","time spent":"1.139291809s","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T15:45:21.953844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:21.40951Z","time spent":"544.324899ms","remote":"127.0.0.1:45836","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-01T15:45:21.953753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:20.816763Z","time spent":"1.136985385s","remote":"127.0.0.1:45836","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-01T15:45:21.954398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140420786s","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-01T15:45:21.954448Z","caller":"traceutil/trace.go:171","msg":"trace[210909181] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"1.214841859s","start":"2026-04-01T15:45:20.738652Z","end":"2026-04-01T15:45:21.953494Z","steps":["trace[210909181] 'process raft request' (duration: 1.214609534s)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:21.954458Z","caller":"traceutil/trace.go:171","msg":"trace[2087523436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1134; }","duration":"1.140638682s","start":"2026-04-01T15:45:20.813802Z","end":"2026-04-01T15:45:21.954441Z","steps":["trace[2087523436] 'agreement among raft nodes before linearized reading' (duration: 1.139641958s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:21.9545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:20.813786Z","time spent":"1.140706263s","remote":"127.0.0.1:45836","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-01T15:45:21.954565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:20.738638Z","time spent":"1.215849403s","remote":"127.0.0.1:45836","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-01T15:45:22.565871Z","caller":"traceutil/trace.go:171","msg":"trace[346909805] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"607.227351ms","start":"2026-04-01T15:45:21.958621Z","end":"2026-04-01T15:45:22.565848Z","steps":["trace[346909805] 'process raft request' (duration: 607.087378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:22.565996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:21.958601Z","time spent":"607.330533ms","remote":"127.0.0.1:45836","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-01T15:45:22.566969Z","caller":"traceutil/trace.go:171","msg":"trace[1862611182] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"608.303916ms","start":"2026-04-01T15:45:21.958646Z","end":"2026-04-01T15:45:22.56695Z","steps":["trace[1862611182] 'process raft request' (duration: 608.169343ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:22.567321Z","caller":"traceutil/trace.go:171","msg":"trace[683993372] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"608.534531ms","start":"2026-04-01T15:45:21.958776Z","end":"2026-04-01T15:45:22.567311Z","steps":["trace[683993372] 'process raft request' (duration: 608.146242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:22.567378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:21.95877Z","time spent":"608.580832ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:45:22.567389Z","caller":"traceutil/trace.go:171","msg":"trace[584671448] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1180; }","duration":"447.545889ms","start":"2026-04-01T15:45:22.119819Z","end":"2026-04-01T15:45:22.567365Z","steps":["trace[584671448] 'read index received' (duration: 445.871379ms)","trace[584671448] 'applied index is now lower than readState.Index' (duration: 1.67318ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:22.567448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:21.958629Z","time spent":"608.750676ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:45:22.567498Z","caller":"traceutil/trace.go:171","msg":"trace[857282448] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"608.661595ms","start":"2026-04-01T15:45:21.958827Z","end":"2026-04-01T15:45:22.567489Z","steps":["trace[857282448] 'process raft request' (duration: 608.343387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:22.567542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:21.958819Z","time spent":"608.700535ms","remote":"127.0.0.1:45836","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-01T15:45:22.567547Z","caller":"traceutil/trace.go:171","msg":"trace[1815293117] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"608.617654ms","start":"2026-04-01T15:45:21.958917Z","end":"2026-04-01T15:45:22.567535Z","steps":["trace[1815293117] 'process raft request' (duration: 608.374178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:22.567632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.816975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-01T15:45:22.567657Z","caller":"traceutil/trace.go:171","msg":"trace[1868167144] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1139; }","duration":"447.878796ms","start":"2026-04-01T15:45:22.119769Z","end":"2026-04-01T15:45:22.567648Z","steps":["trace[1868167144] 'agreement among raft nodes before linearized reading' (duration: 447.826795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:22.567647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:21.958895Z","time spent":"608.680445ms","remote":"127.0.0.1:45836","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-01T15:45:22.567677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:22.119752Z","time spent":"447.919598ms","remote":"127.0.0.1:46040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-01T15:45:22.567741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.659821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T15:45:22.567789Z","caller":"traceutil/trace.go:171","msg":"trace[2122009170] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:1139; }","duration":"113.745103ms","start":"2026-04-01T15:45:22.45403Z","end":"2026-04-01T15:45:22.567775Z","steps":["trace[2122009170] 'agreement among raft nodes before linearized reading' (duration: 113.525518ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:36.482084Z","caller":"traceutil/trace.go:171","msg":"trace[394491616] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"113.691741ms","start":"2026-04-01T15:45:36.368361Z","end":"2026-04-01T15:45:36.482052Z","steps":["trace[394491616] 'process raft request' (duration: 42.325184ms)","trace[394491616] 'compare' (duration: 71.230984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:37.534858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.165605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T15:45:37.534954Z","caller":"traceutil/trace.go:171","msg":"trace[1026707436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1197; }","duration":"899.291909ms","start":"2026-04-01T15:45:36.635635Z","end":"2026-04-01T15:45:37.534927Z","steps":["trace[1026707436] 'range keys from in-memory index tree' (duration: 899.020462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:37.535007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:36.635624Z","time spent":"899.37067ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T15:45:37.535031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.511606ms","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-01T15:45:37.535121Z","caller":"traceutil/trace.go:171","msg":"trace[205650239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1197; }","duration":"897.64136ms","start":"2026-04-01T15:45:36.637459Z","end":"2026-04-01T15:45:37.5351Z","steps":["trace[205650239] 'range keys from in-memory index tree' (duration: 897.346522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:37.535135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.340541ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:45:37.53516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:36.637449Z","time spent":"897.702831ms","remote":"127.0.0.1:45836","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-01T15:45:37.535223Z","caller":"traceutil/trace.go:171","msg":"trace[1010922675] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1197; }","duration":"891.394252ms","start":"2026-04-01T15:45:36.643775Z","end":"2026-04-01T15:45:37.535169Z","steps":["trace[1010922675] 'range keys from in-memory index tree' (duration: 891.329971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:37.535228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.084028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-bvm8f\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-04-01T15:45:37.535283Z","caller":"traceutil/trace.go:171","msg":"trace[106938872] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-bvm8f; range_end:; response_count:1; response_revision:1197; }","duration":"909.159121ms","start":"2026-04-01T15:45:36.626107Z","end":"2026-04-01T15:45:37.535266Z","steps":["trace[106938872] 'range keys from in-memory index tree' (duration: 908.905804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:37.535269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.623814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-01T15:45:37.535314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.784257ms","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-01T15:45:37.535319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:36.626096Z","time spent":"909.214292ms","remote":"127.0.0.1:45748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11080,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-bvm8f\" "} {"level":"info","ts":"2026-04-01T15:45:37.53535Z","caller":"traceutil/trace.go:171","msg":"trace[922789821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"907.831338ms","start":"2026-04-01T15:45:36.627508Z","end":"2026-04-01T15:45:37.535339Z","steps":["trace[922789821] 'range keys from in-memory index tree' (duration: 907.706296ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:37.535331Z","caller":"traceutil/trace.go:171","msg":"trace[1843101381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1197; }","duration":"910.720977ms","start":"2026-04-01T15:45:36.624594Z","end":"2026-04-01T15:45:37.535315Z","steps":["trace[1843101381] 'range keys from in-memory index tree' (duration: 910.510532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:37.53538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:36.627503Z","time spent":"907.8702ms","remote":"127.0.0.1:45836","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-01T15:45:37.535396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:36.624581Z","time spent":"910.807149ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T15:45:37.53539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.399321ms","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-01T15:45:37.535661Z","caller":"traceutil/trace.go:171","msg":"trace[2104527299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1197; }","duration":"902.706398ms","start":"2026-04-01T15:45:36.632937Z","end":"2026-04-01T15:45:37.535644Z","steps":["trace[2104527299] 'range keys from in-memory index tree' (duration: 902.265738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:37.535713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.715548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-01T15:45:37.535774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:36.632922Z","time spent":"902.841661ms","remote":"127.0.0.1:45836","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-01T15:45:37.53581Z","caller":"traceutil/trace.go:171","msg":"trace[1965040578] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1246; }","duration":"253.104147ms","start":"2026-04-01T15:45:37.282695Z","end":"2026-04-01T15:45:37.535799Z","steps":["trace[1965040578] 'read index received' (duration: 44.041µs)","trace[1965040578] 'applied index is now lower than readState.Index' (duration: 253.058566ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:45:37.535823Z","caller":"traceutil/trace.go:171","msg":"trace[192473900] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"434.276443ms","start":"2026-04-01T15:45:37.101526Z","end":"2026-04-01T15:45:37.535802Z","steps":["trace[192473900] 'process raft request' (duration: 155.33786ms)","trace[192473900] 'compare' (duration: 277.95744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:37.535896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.207199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:45:37.535964Z","caller":"traceutil/trace.go:171","msg":"trace[423748881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"253.312762ms","start":"2026-04-01T15:45:37.282642Z","end":"2026-04-01T15:45:37.535954Z","steps":["trace[423748881] 'agreement among raft nodes before linearized reading' (duration: 253.193909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:37.535925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.170771ms","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":"warn","ts":"2026-04-01T15:45:37.535996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:37.101498Z","time spent":"434.405127ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T15:45:37.53604Z","caller":"traceutil/trace.go:171","msg":"trace[183439592] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1198; }","duration":"188.306134ms","start":"2026-04-01T15:45:37.347718Z","end":"2026-04-01T15:45:37.536024Z","steps":["trace[183439592] 'agreement among raft nodes before linearized reading' (duration: 188.169381ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:37.711077Z","caller":"traceutil/trace.go:171","msg":"trace[779376419] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"169.220226ms","start":"2026-04-01T15:45:37.54184Z","end":"2026-04-01T15:45:37.71106Z","steps":["trace[779376419] 'process raft request' (duration: 169.162974ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:37.711131Z","caller":"traceutil/trace.go:171","msg":"trace[740474711] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"169.314008ms","start":"2026-04-01T15:45:37.541786Z","end":"2026-04-01T15:45:37.7111Z","steps":["trace[740474711] 'process raft request' (duration: 169.118143ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:37.711287Z","caller":"traceutil/trace.go:171","msg":"trace[1128321438] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"169.209646ms","start":"2026-04-01T15:45:37.542056Z","end":"2026-04-01T15:45:37.711266Z","steps":["trace[1128321438] 'process raft request' (duration: 168.96942ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:37.71135Z","caller":"traceutil/trace.go:171","msg":"trace[1413366605] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"168.680203ms","start":"2026-04-01T15:45:37.542662Z","end":"2026-04-01T15:45:37.711342Z","steps":["trace[1413366605] 'process raft request' (duration: 168.51636ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:37.711329Z","caller":"traceutil/trace.go:171","msg":"trace[1009760313] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"164.031504ms","start":"2026-04-01T15:45:37.547278Z","end":"2026-04-01T15:45:37.711309Z","steps":["trace[1009760313] 'process raft request' (duration: 163.989353ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:37.913501Z","caller":"traceutil/trace.go:171","msg":"trace[709318348] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"195.186576ms","start":"2026-04-01T15:45:37.718292Z","end":"2026-04-01T15:45:37.913478Z","steps":["trace[709318348] 'process raft request' (duration: 194.722585ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:45:37.913889Z","caller":"traceutil/trace.go:171","msg":"trace[1958231053] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"191.822326ms","start":"2026-04-01T15:45:37.722023Z","end":"2026-04-01T15:45:37.913845Z","steps":["trace[1958231053] 'process raft request' (duration: 191.401136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:38.017548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.913771ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-01T15:45:40.036847Z","caller":"traceutil/trace.go:171","msg":"trace[774104111] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"321.525413ms","start":"2026-04-01T15:45:39.715297Z","end":"2026-04-01T15:45:40.036822Z","steps":["trace[774104111] 'read index received' (duration: 262.515457ms)","trace[774104111] 'applied index is now lower than readState.Index' (duration: 59.008846ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:45:40.036928Z","caller":"traceutil/trace.go:171","msg":"trace[999224019] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"370.454973ms","start":"2026-04-01T15:45:39.666395Z","end":"2026-04-01T15:45:40.03685Z","steps":["trace[999224019] 'process raft request' (duration: 311.467197ms)","trace[999224019] 'compare' (duration: 58.791042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T15:45:40.037117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.793701ms","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-01T15:45:40.037111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:39.666372Z","time spent":"370.648998ms","remote":"127.0.0.1:45628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T15:45:40.037163Z","caller":"traceutil/trace.go:171","msg":"trace[1947987098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1210; }","duration":"321.885552ms","start":"2026-04-01T15:45:39.715267Z","end":"2026-04-01T15:45:40.037153Z","steps":["trace[1947987098] 'agreement among raft nodes before linearized reading' (duration: 321.686978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:40.037208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:39.715252Z","time spent":"321.947234ms","remote":"127.0.0.1:45836","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-01T15:45:40.037204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.305779ms","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-01T15:45:40.037281Z","caller":"traceutil/trace.go:171","msg":"trace[1518837605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"321.399141ms","start":"2026-04-01T15:45:39.715862Z","end":"2026-04-01T15:45:40.037261Z","steps":["trace[1518837605] 'agreement among raft nodes before linearized reading' (duration: 321.195846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:40.037326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:39.715857Z","time spent":"321.460902ms","remote":"127.0.0.1:45836","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-01T15:45:40.037489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.861258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T15:45:40.037542Z","caller":"traceutil/trace.go:171","msg":"trace[286956442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"320.94782ms","start":"2026-04-01T15:45:39.716582Z","end":"2026-04-01T15:45:40.037529Z","steps":["trace[286956442] 'agreement among raft nodes before linearized reading' (duration: 320.847468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:40.037588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:39.716561Z","time spent":"321.015462ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T15:45:40.037762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.325109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-01T15:45:40.037857Z","caller":"traceutil/trace.go:171","msg":"trace[545074781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"321.434411ms","start":"2026-04-01T15:45:39.716407Z","end":"2026-04-01T15:45:40.037841Z","steps":["trace[545074781] 'agreement among raft nodes before linearized reading' (duration: 321.157485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:40.037899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:39.716403Z","time spent":"321.487363ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T15:45:51.210121Z","caller":"traceutil/trace.go:171","msg":"trace[1889362286] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"152.754549ms","start":"2026-04-01T15:45:51.057341Z","end":"2026-04-01T15:45:51.210096Z","steps":["trace[1889362286] 'process raft request' (duration: 152.541214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:52.173707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.563219ms","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-01T15:45:52.17386Z","caller":"traceutil/trace.go:171","msg":"trace[762478821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1294; }","duration":"465.760493ms","start":"2026-04-01T15:45:51.70808Z","end":"2026-04-01T15:45:52.17384Z","steps":["trace[762478821] 'count revisions from in-memory index tree' (duration: 465.475097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:52.173909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:51.708058Z","time spent":"465.835805ms","remote":"127.0.0.1:35004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-01T15:45:52.173898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.207783ms","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-01T15:45:52.173976Z","caller":"traceutil/trace.go:171","msg":"trace[1195822464] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1294; }","duration":"898.331246ms","start":"2026-04-01T15:45:51.275624Z","end":"2026-04-01T15:45:52.173956Z","steps":["trace[1195822464] 'count revisions from in-memory index tree' (duration: 898.113201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:52.17399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.296799ms","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-01T15:45:52.174062Z","caller":"traceutil/trace.go:171","msg":"trace[1500987709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1294; }","duration":"421.396511ms","start":"2026-04-01T15:45:51.752646Z","end":"2026-04-01T15:45:52.174042Z","steps":["trace[1500987709] 'range keys from in-memory index tree' (duration: 421.054533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:45:52.1741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:51.752635Z","time spent":"421.456433ms","remote":"127.0.0.1:45836","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-01T15:45:52.174017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:45:51.275603Z","time spent":"898.404079ms","remote":"127.0.0.1:35072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-01T15:46:44.634959Z","caller":"traceutil/trace.go:171","msg":"trace[943140927] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"103.877554ms","start":"2026-04-01T15:46:44.531063Z","end":"2026-04-01T15:46:44.634941Z","steps":["trace[943140927] 'process raft request' (duration: 98.344844ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:46:44.635139Z","caller":"traceutil/trace.go:171","msg":"trace[1560878283] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"103.797171ms","start":"2026-04-01T15:46:44.531301Z","end":"2026-04-01T15:46:44.635098Z","steps":["trace[1560878283] 'process raft request' (duration: 103.600117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:46:44.635148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.980063ms","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-01T15:46:44.635487Z","caller":"traceutil/trace.go:171","msg":"trace[583015677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1540; }","duration":"103.366482ms","start":"2026-04-01T15:46:44.532108Z","end":"2026-04-01T15:46:44.635474Z","steps":["trace[583015677] 'agreement among raft nodes before linearized reading' (duration: 102.896661ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:47:45.105406Z","caller":"traceutil/trace.go:171","msg":"trace[280016635] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"136.430344ms","start":"2026-04-01T15:47:44.968953Z","end":"2026-04-01T15:47:45.105383Z","steps":["trace[280016635] 'process raft request' (duration: 89.417043ms)","trace[280016635] 'compare' (duration: 46.839787ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:47:45.107075Z","caller":"traceutil/trace.go:171","msg":"trace[1648409479] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"137.332865ms","start":"2026-04-01T15:47:44.969724Z","end":"2026-04-01T15:47:45.107057Z","steps":["trace[1648409479] 'process raft request' (duration: 137.194362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:48:54.229399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155512976639310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:48:54.73021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088155512976639310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T15:48:54.88761Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.213676847s","expected-duration":"1s"} {"level":"info","ts":"2026-04-01T15:48:54.888081Z","caller":"traceutil/trace.go:171","msg":"trace[1781034876] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2247; }","duration":"1.158775995s","start":"2026-04-01T15:48:53.729283Z","end":"2026-04-01T15:48:54.888059Z","steps":["trace[1781034876] 'read index received' (duration: 1.158535839s)","trace[1781034876] 'applied index is now lower than readState.Index' (duration: 239.116µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:48:54.888308Z","caller":"traceutil/trace.go:171","msg":"trace[46406463] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"1.214474196s","start":"2026-04-01T15:48:53.67381Z","end":"2026-04-01T15:48:54.888284Z","steps":["trace[46406463] 'process raft request' (duration: 1.214078107s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:48:54.888343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.819614ms","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":"warn","ts":"2026-04-01T15:48:54.888312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15901024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2722"} {"level":"info","ts":"2026-04-01T15:48:54.888407Z","caller":"traceutil/trace.go:171","msg":"trace[872214848] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2158; }","duration":"893.934157ms","start":"2026-04-01T15:48:53.994456Z","end":"2026-04-01T15:48:54.88839Z","steps":["trace[872214848] 'agreement among raft nodes before linearized reading' (duration: 893.781873ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:48:54.888413Z","caller":"traceutil/trace.go:171","msg":"trace[1488199429] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2158; }","duration":"1.159157954s","start":"2026-04-01T15:48:53.72924Z","end":"2026-04-01T15:48:54.888397Z","steps":["trace[1488199429] 'agreement among raft nodes before linearized reading' (duration: 1.158919558s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:48:54.888434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:48:53.673787Z","time spent":"1.214580318s","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T15:48:54.888455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:48:53.994435Z","time spent":"894.008969ms","remote":"127.0.0.1:35028","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-01T15:48:54.88843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.704746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T15:48:54.888463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:48:53.729222Z","time spent":"1.159227905s","remote":"127.0.0.1:45748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2745,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-04-01T15:48:54.888515Z","caller":"traceutil/trace.go:171","msg":"trace[1678364477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2158; }","duration":"606.839269ms","start":"2026-04-01T15:48:54.281656Z","end":"2026-04-01T15:48:54.888495Z","steps":["trace[1678364477] 'agreement among raft nodes before linearized reading' (duration: 606.673505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:48:54.888471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.840954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-01T15:48:54.88856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:48:54.281637Z","time spent":"606.91508ms","remote":"127.0.0.1:45538","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-01T15:48:54.888585Z","caller":"traceutil/trace.go:171","msg":"trace[467807026] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2158; }","duration":"757.009428ms","start":"2026-04-01T15:48:54.13155Z","end":"2026-04-01T15:48:54.88856Z","steps":["trace[467807026] 'agreement among raft nodes before linearized reading' (duration: 756.775622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:48:54.888634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:48:54.131524Z","time spent":"757.09879ms","remote":"127.0.0.1:46130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-01T15:49:02.092765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.991723ms","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-01T15:49:02.092868Z","caller":"traceutil/trace.go:171","msg":"trace[1312760989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2193; }","duration":"845.149856ms","start":"2026-04-01T15:49:01.247696Z","end":"2026-04-01T15:49:02.092846Z","steps":["trace[1312760989] 'range keys from in-memory index tree' (duration: 844.774977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:49:02.092908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:49:01.247662Z","time spent":"845.232009ms","remote":"127.0.0.1:45836","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-01T15:49:02.093197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.232501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T15:49:02.093267Z","caller":"traceutil/trace.go:171","msg":"trace[2040327821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2193; }","duration":"810.348184ms","start":"2026-04-01T15:49:01.2829Z","end":"2026-04-01T15:49:02.093248Z","steps":["trace[2040327821] 'range keys from in-memory index tree' (duration: 810.145499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:49:02.093307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:49:01.282878Z","time spent":"810.419625ms","remote":"127.0.0.1:45538","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-01T15:49:02.09342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.328134ms","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-01T15:49:02.093482Z","caller":"traceutil/trace.go:171","msg":"trace[1053886288] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2193; }","duration":"442.453028ms","start":"2026-04-01T15:49:01.65101Z","end":"2026-04-01T15:49:02.093463Z","steps":["trace[1053886288] 'range keys from in-memory index tree' (duration: 442.12832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:49:02.093546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:49:01.650987Z","time spent":"442.546179ms","remote":"127.0.0.1:45836","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":"warn","ts":"2026-04-01T15:49:02.093654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.714843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-01T15:49:02.093683Z","caller":"traceutil/trace.go:171","msg":"trace[1928322537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2193; }","duration":"751.777315ms","start":"2026-04-01T15:49:01.341896Z","end":"2026-04-01T15:49:02.093674Z","steps":["trace[1928322537] 'range keys from in-memory index tree' (duration: 751.606551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:49:02.093709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:49:01.341874Z","time spent":"751.827726ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-01T15:49:02.093716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.524651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-01T15:49:02.093756Z","caller":"traceutil/trace.go:171","msg":"trace[1142056392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2193; }","duration":"573.598571ms","start":"2026-04-01T15:49:01.520145Z","end":"2026-04-01T15:49:02.093744Z","steps":["trace[1142056392] 'range keys from in-memory index tree' (duration: 573.331545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:49:02.093785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:49:01.520127Z","time spent":"573.650693ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T15:49:02.093896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.998373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T15:49:02.093916Z","caller":"traceutil/trace.go:171","msg":"trace[1483852265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2193; }","duration":"640.053234ms","start":"2026-04-01T15:49:01.453856Z","end":"2026-04-01T15:49:02.093909Z","steps":["trace[1483852265] 'range keys from in-memory index tree' (duration: 639.85457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:49:02.093932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:49:01.453836Z","time spent":"640.092466ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T15:49:02.093994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.647316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-04-01T15:49:02.093974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.556979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-01T15:49:02.094022Z","caller":"traceutil/trace.go:171","msg":"trace[1788313511] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2193; }","duration":"571.693048ms","start":"2026-04-01T15:49:01.52232Z","end":"2026-04-01T15:49:02.094013Z","steps":["trace[1788313511] 'range keys from in-memory index tree' (duration: 571.550924ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:49:02.094044Z","caller":"traceutil/trace.go:171","msg":"trace[529767368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2193; }","duration":"638.666252ms","start":"2026-04-01T15:49:01.455363Z","end":"2026-04-01T15:49:02.094029Z","steps":["trace[529767368] 'range keys from in-memory index tree' (duration: 638.413966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:49:02.094054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:49:01.522311Z","time spent":"571.735708ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-01T15:49:02.094085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T15:49:01.455343Z","time spent":"638.731034ms","remote":"127.0.0.1:45836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T15:49:29.44573Z","caller":"traceutil/trace.go:171","msg":"trace[822707548] transaction","detail":"{read_only:false; response_revision:2329; number_of_response:1; }","duration":"130.443858ms","start":"2026-04-01T15:49:29.31526Z","end":"2026-04-01T15:49:29.445704Z","steps":["trace[822707548] 'process raft request' (duration: 130.288354ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:49:30.450792Z","caller":"traceutil/trace.go:171","msg":"trace[1225136244] transaction","detail":"{read_only:false; response_revision:2334; number_of_response:1; }","duration":"103.13133ms","start":"2026-04-01T15:49:30.347637Z","end":"2026-04-01T15:49:30.450768Z","steps":["trace[1225136244] 'process raft request' (duration: 61.60286ms)","trace[1225136244] 'compare' (duration: 41.379266ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:49:30.451125Z","caller":"traceutil/trace.go:171","msg":"trace[1219907342] transaction","detail":"{read_only:false; response_revision:2335; number_of_response:1; }","duration":"102.827523ms","start":"2026-04-01T15:49:30.348273Z","end":"2026-04-01T15:49:30.451101Z","steps":["trace[1219907342] 'process raft request' (duration: 102.456214ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T15:49:30.451208Z","caller":"traceutil/trace.go:171","msg":"trace[523892061] linearizableReadLoop","detail":"{readStateIndex:2433; appliedIndex:2430; }","duration":"100.397106ms","start":"2026-04-01T15:49:30.35079Z","end":"2026-04-01T15:49:30.451187Z","steps":["trace[523892061] 'read index received' (duration: 58.461366ms)","trace[523892061] 'applied index is now lower than readState.Index' (duration: 41.93465ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T15:49:30.451238Z","caller":"traceutil/trace.go:171","msg":"trace[545844499] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"102.423003ms","start":"2026-04-01T15:49:30.3488Z","end":"2026-04-01T15:49:30.451223Z","steps":["trace[545844499] 'process raft request' (duration: 102.175038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T15:49:30.451861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.052111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:115 size:88923"} {"level":"info","ts":"2026-04-01T15:49:30.451941Z","caller":"traceutil/trace.go:171","msg":"trace[1603021364] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:115; response_revision:2336; }","duration":"101.159564ms","start":"2026-04-01T15:49:30.350763Z","end":"2026-04-01T15:49:30.451923Z","steps":["trace[1603021364] 'agreement among raft nodes before linearized reading' (duration: 100.518369ms)"],"step_count":1}