{"level":"warn","ts":"2026-04-14T10:46:47.131668Z","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-14T10:46:47.131756Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.168:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.168:2380","--initial-cluster=instance=https://199.19.213.168:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.168:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.168: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-14T10:46:47.131818Z","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-14T10:46:47.131832Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.168:2380"]} {"level":"info","ts":"2026-04-14T10:46:47.131852Z","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-14T10:46:47.132507Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"]} {"level":"info","ts":"2026-04-14T10:46:47.132589Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.168:2380"],"listen-peer-urls":["https://199.19.213.168:2380"],"advertise-client-urls":["https://199.19.213.168:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.168: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-14T10:46:47.137629Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.860406ms"} {"level":"info","ts":"2026-04-14T10:46:47.146848Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8ae391decac9b376","cluster-id":"d9b32c15a2c66ac"} {"level":"info","ts":"2026-04-14T10:46:47.147256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T10:46:47.147329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became follower at term 0"} {"level":"info","ts":"2026-04-14T10:46:47.147409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8ae391decac9b376 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T10:46:47.147463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became follower at term 1"} {"level":"info","ts":"2026-04-14T10:46:47.147587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=(10008003183018357622)"} {"level":"warn","ts":"2026-04-14T10:46:47.155644Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T10:46:47.157664Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T10:46:47.159316Z","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-14T10:46:47.162783Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8ae391decac9b376","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T10:46:47.1633Z","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-14T10:46:47.163395Z","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-14T10:46:47.163406Z","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-14T10:46:47.163622Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8ae391decac9b376","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T10:46:47.164257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 switched to configuration voters=(10008003183018357622)"} {"level":"info","ts":"2026-04-14T10:46:47.164444Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d9b32c15a2c66ac","local-member-id":"8ae391decac9b376","added-peer-id":"8ae391decac9b376","added-peer-peer-urls":["https://199.19.213.168:2380"]} {"level":"info","ts":"2026-04-14T10:46:47.170057Z","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-14T10:46:47.170207Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.168:2380"} {"level":"info","ts":"2026-04-14T10:46:47.170256Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.168:2380"} {"level":"info","ts":"2026-04-14T10:46:47.170332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8ae391decac9b376","initial-advertise-peer-urls":["https://199.19.213.168:2380"],"listen-peer-urls":["https://199.19.213.168:2380"],"advertise-client-urls":["https://199.19.213.168:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.168:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T10:46:47.170383Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T10:46:48.049406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T10:46:48.049481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T10:46:48.049525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 received MsgPreVoteResp from 8ae391decac9b376 at term 1"} {"level":"info","ts":"2026-04-14T10:46:48.049554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became candidate at term 2"} {"level":"info","ts":"2026-04-14T10:46:48.04957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 received MsgVoteResp from 8ae391decac9b376 at term 2"} {"level":"info","ts":"2026-04-14T10:46:48.049589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ae391decac9b376 became leader at term 2"} {"level":"info","ts":"2026-04-14T10:46:48.049606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8ae391decac9b376 elected leader 8ae391decac9b376 at term 2"} {"level":"info","ts":"2026-04-14T10:46:48.050728Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:46:48.051002Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8ae391decac9b376","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.168:2379]}","request-path":"/0/members/8ae391decac9b376/attributes","cluster-id":"d9b32c15a2c66ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T10:46:48.051005Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T10:46:48.051246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T10:46:48.051767Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d9b32c15a2c66ac","local-member-id":"8ae391decac9b376","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:46:48.051883Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:46:48.051913Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T10:46:48.051932Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T10:46:48.051955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T10:46:48.052922Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.168:2379"} {"level":"info","ts":"2026-04-14T10:46:48.054682Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-14T10:46:53.889823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.3093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:46:53.88999Z","caller":"traceutil/trace.go:171","msg":"trace[1122081721] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:251; }","duration":"119.538555ms","start":"2026-04-14T10:46:53.770423Z","end":"2026-04-14T10:46:53.889962Z","steps":["trace[1122081721] 'range keys from in-memory index tree' (duration: 119.174007ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:46:54.091584Z","caller":"traceutil/trace.go:171","msg":"trace[1533898065] linearizableReadLoop","detail":"{readStateIndex:261; appliedIndex:260; }","duration":"109.172383ms","start":"2026-04-14T10:46:53.982253Z","end":"2026-04-14T10:46:54.091426Z","steps":["trace[1533898065] 'read index received' (duration: 58.596499ms)","trace[1533898065] 'applied index is now lower than readState.Index' (duration: 50.574634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:46:54.091703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.431508ms","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-14T10:46:54.091699Z","caller":"traceutil/trace.go:171","msg":"trace[456717274] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"108.0142ms","start":"2026-04-14T10:46:53.983671Z","end":"2026-04-14T10:46:54.091685Z","steps":["trace[456717274] 'process raft request' (duration: 107.903687ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:46:54.091769Z","caller":"traceutil/trace.go:171","msg":"trace[1698713104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:253; }","duration":"109.550051ms","start":"2026-04-14T10:46:53.982202Z","end":"2026-04-14T10:46:54.091752Z","steps":["trace[1698713104] 'agreement among raft nodes before linearized reading' (duration: 109.407968ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:46:54.091483Z","caller":"traceutil/trace.go:171","msg":"trace[497543221] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"194.281285ms","start":"2026-04-14T10:46:53.897159Z","end":"2026-04-14T10:46:54.09144Z","steps":["trace[497543221] 'process raft request' (duration: 143.677539ms)","trace[497543221] 'compare' (duration: 50.36036ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:03.306346Z","caller":"traceutil/trace.go:171","msg":"trace[456255000] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"132.486459ms","start":"2026-04-14T10:47:03.173836Z","end":"2026-04-14T10:47:03.306323Z","steps":["trace[456255000] 'process raft request' (duration: 132.343315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:03.536509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.916783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:03.536865Z","caller":"traceutil/trace.go:171","msg":"trace[464414646] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"134.460702ms","start":"2026-04-14T10:47:03.40236Z","end":"2026-04-14T10:47:03.53682Z","steps":["trace[464414646] 'process raft request' (duration: 21.608138ms)","trace[464414646] 'compare' (duration: 111.792471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:12.310894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678154,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T10:47:12.528562Z","caller":"traceutil/trace.go:171","msg":"trace[1759044283] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"718.432988ms","start":"2026-04-14T10:47:11.810104Z","end":"2026-04-14T10:47:12.528537Z","steps":["trace[1759044283] 'read index received' (duration: 718.292505ms)","trace[1759044283] 'applied index is now lower than readState.Index' (duration: 139.623µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:12.528616Z","caller":"traceutil/trace.go:171","msg":"trace[480192157] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"932.802625ms","start":"2026-04-14T10:47:11.595765Z","end":"2026-04-14T10:47:12.528568Z","steps":["trace[480192157] 'process raft request' (duration: 932.623831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:12.52874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.613042ms","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-14T10:47:12.528779Z","caller":"traceutil/trace.go:171","msg":"trace[39099394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"718.698834ms","start":"2026-04-14T10:47:11.810071Z","end":"2026-04-14T10:47:12.52877Z","steps":["trace[39099394] 'agreement among raft nodes before linearized reading' (duration: 718.612642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:12.528822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:11.810055Z","time spent":"718.751145ms","remote":"127.0.0.1:39470","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-14T10:47:12.52879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:11.595737Z","time spent":"932.963568ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:12.528982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.079304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:12.528988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.81216ms","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-14T10:47:12.529091Z","caller":"traceutil/trace.go:171","msg":"trace[1792776088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"402.237677ms","start":"2026-04-14T10:47:12.126829Z","end":"2026-04-14T10:47:12.529067Z","steps":["trace[1792776088] 'agreement among raft nodes before linearized reading' (duration: 402.044043ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:12.529098Z","caller":"traceutil/trace.go:171","msg":"trace[957370966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"295.954772ms","start":"2026-04-14T10:47:12.233123Z","end":"2026-04-14T10:47:12.529078Z","steps":["trace[957370966] 'agreement among raft nodes before linearized reading' (duration: 295.797089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:12.529136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:12.126813Z","time spent":"402.31487ms","remote":"127.0.0.1:39184","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-14T10:47:20.628385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678293,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T10:47:20.867767Z","caller":"traceutil/trace.go:171","msg":"trace[762383868] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"745.403393ms","start":"2026-04-14T10:47:20.122313Z","end":"2026-04-14T10:47:20.867716Z","steps":["trace[762383868] 'process raft request' (duration: 693.753012ms)","trace[762383868] 'compare' (duration: 50.980075ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:20.867936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.122299Z","time spent":"745.552417ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:21.788929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.694481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:21.789367Z","caller":"traceutil/trace.go:171","msg":"trace[1320184133] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.666795395s","start":"2026-04-14T10:47:20.122529Z","end":"2026-04-14T10:47:21.789325Z","steps":["trace[1320184133] 'process raft request' (duration: 979.577171ms)","trace[1320184133] 'compare' (duration: 686.556458ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:21.789419Z","caller":"traceutil/trace.go:171","msg":"trace[1519068865] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.662152934s","start":"2026-04-14T10:47:20.127249Z","end":"2026-04-14T10:47:21.789402Z","steps":["trace[1519068865] 'process raft request' (duration: 1.661831266s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:21.789514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.127218Z","time spent":"1.662228195s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:21.789566Z","caller":"traceutil/trace.go:171","msg":"trace[961451799] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.629146339s","start":"2026-04-14T10:47:20.160411Z","end":"2026-04-14T10:47:21.789557Z","steps":["trace[961451799] 'process raft request' (duration: 1.628933234s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:21.789516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.122521Z","time spent":"1.666920967s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:21.789604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.160375Z","time spent":"1.629211761s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:21.789628Z","caller":"traceutil/trace.go:171","msg":"trace[2056103364] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.492043495s","start":"2026-04-14T10:47:20.297576Z","end":"2026-04-14T10:47:21.789619Z","steps":["trace[2056103364] 'process raft request' (duration: 1.49183448s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:21.789706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.297553Z","time spent":"1.492116747s","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:21.789704Z","caller":"traceutil/trace.go:171","msg":"trace[170034365] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:522; }","duration":"1.661819965s","start":"2026-04-14T10:47:20.127827Z","end":"2026-04-14T10:47:21.789647Z","steps":["trace[170034365] 'read index received' (duration: 688.328002ms)","trace[170034365] 'applied index is now lower than readState.Index' (duration: 973.485913ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:21.789797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.661975779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:21.789566Z","caller":"traceutil/trace.go:171","msg":"trace[34273379] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"907.911578ms","start":"2026-04-14T10:47:20.881636Z","end":"2026-04-14T10:47:21.789548Z","steps":["trace[34273379] 'process raft request' (duration: 907.853957ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:21.789828Z","caller":"traceutil/trace.go:171","msg":"trace[214511162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"1.662054121s","start":"2026-04-14T10:47:20.127766Z","end":"2026-04-14T10:47:21.78982Z","steps":["trace[214511162] 'agreement among raft nodes before linearized reading' (duration: 1.661944998s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:21.789846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.127756Z","time spent":"1.662086231s","remote":"127.0.0.1:39184","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-14T10:47:21.789871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.881582Z","time spent":"908.249926ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:21.789989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143826307s","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-14T10:47:21.790032Z","caller":"traceutil/trace.go:171","msg":"trace[798662014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:505; }","duration":"1.143922259s","start":"2026-04-14T10:47:20.646098Z","end":"2026-04-14T10:47:21.79002Z","steps":["trace[798662014] 'agreement among raft nodes before linearized reading' (duration: 1.143746215s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:21.790074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.646074Z","time spent":"1.143992042s","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T10:47:21.790113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053710082s","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-14T10:47:21.790155Z","caller":"traceutil/trace.go:171","msg":"trace[2003153074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:505; }","duration":"1.053773153s","start":"2026-04-14T10:47:20.736371Z","end":"2026-04-14T10:47:21.790144Z","steps":["trace[2003153074] 'agreement among raft nodes before linearized reading' (duration: 1.053697992s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:21.790188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.736359Z","time spent":"1.053822694s","remote":"127.0.0.1:39470","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-14T10:47:21.790267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061323584s","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-14T10:47:21.790304Z","caller":"traceutil/trace.go:171","msg":"trace[1973614188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"1.061376766s","start":"2026-04-14T10:47:20.728918Z","end":"2026-04-14T10:47:21.790294Z","steps":["trace[1973614188] 'agreement among raft nodes before linearized reading' (duration: 1.061317535s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:21.790334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.491985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.168\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-14T10:47:21.79035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.728908Z","time spent":"1.061435787s","remote":"127.0.0.1:39470","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-14T10:47:21.790369Z","caller":"traceutil/trace.go:171","msg":"trace[1209662620] range","detail":"{range_begin:/registry/masterleases/199.19.213.168; range_end:; response_count:1; response_revision:505; }","duration":"543.563117ms","start":"2026-04-14T10:47:21.246797Z","end":"2026-04-14T10:47:21.79036Z","steps":["trace[1209662620] 'agreement among raft nodes before linearized reading' (duration: 543.507935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:21.790404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:21.246777Z","time spent":"543.621719ms","remote":"127.0.0.1:39232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.168\" "} {"level":"warn","ts":"2026-04-14T10:47:21.790424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061686943s","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-14T10:47:21.790481Z","caller":"traceutil/trace.go:171","msg":"trace[1007445941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:505; }","duration":"1.061780545s","start":"2026-04-14T10:47:20.728685Z","end":"2026-04-14T10:47:21.790465Z","steps":["trace[1007445941] 'agreement among raft nodes before linearized reading' (duration: 1.061664792s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:21.790518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:20.728665Z","time spent":"1.061845577s","remote":"127.0.0.1:39470","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-14T10:47:22.386827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.531401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:22.38692Z","caller":"traceutil/trace.go:171","msg":"trace[1920739240] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"393.738578ms","start":"2026-04-14T10:47:21.993165Z","end":"2026-04-14T10:47:22.386904Z","steps":["trace[1920739240] 'process raft request' (duration: 131.072684ms)","trace[1920739240] 'compare' (duration: 262.413728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:22.386954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:21.993137Z","time spent":"393.80288ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:22.389009Z","caller":"traceutil/trace.go:171","msg":"trace[758706589] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"369.579544ms","start":"2026-04-14T10:47:22.019391Z","end":"2026-04-14T10:47:22.388971Z","steps":["trace[758706589] 'process raft request' (duration: 369.514613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:22.389205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.019383Z","time spent":"369.727068ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:22.389767Z","caller":"traceutil/trace.go:171","msg":"trace[755928456] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"384.864324ms","start":"2026-04-14T10:47:22.00489Z","end":"2026-04-14T10:47:22.389754Z","steps":["trace[755928456] 'process raft request' (duration: 383.700936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:22.38985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.004873Z","time spent":"384.926336ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:23.148947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.464248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:23.149136Z","caller":"traceutil/trace.go:171","msg":"trace[20339408] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"731.321003ms","start":"2026-04-14T10:47:22.417785Z","end":"2026-04-14T10:47:23.149106Z","steps":["trace[20339408] 'process raft request' (duration: 287.631909ms)","trace[20339408] 'compare' (duration: 443.345685ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:23.149174Z","caller":"traceutil/trace.go:171","msg":"trace[1826163570] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"701.672004ms","start":"2026-04-14T10:47:22.447484Z","end":"2026-04-14T10:47:23.149156Z","steps":["trace[1826163570] 'process raft request' (duration: 701.610482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.149212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.447458Z","time spent":"701.740636ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:23.149252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.417763Z","time spent":"731.432205ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:23.153469Z","caller":"traceutil/trace.go:171","msg":"trace[1362492618] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"639.238911ms","start":"2026-04-14T10:47:22.514219Z","end":"2026-04-14T10:47:23.153458Z","steps":["trace[1362492618] 'process raft request' (duration: 638.507854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:23.153527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:22.514175Z","time spent":"639.322423ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:28.948675Z","caller":"traceutil/trace.go:171","msg":"trace[254379440] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"309.738654ms","start":"2026-04-14T10:47:28.638862Z","end":"2026-04-14T10:47:28.9486Z","steps":["trace[254379440] 'read index received' (duration: 304.384642ms)","trace[254379440] 'applied index is now lower than readState.Index' (duration: 5.351902ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:28.949236Z","caller":"traceutil/trace.go:171","msg":"trace[211838879] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"324.597983ms","start":"2026-04-14T10:47:28.624618Z","end":"2026-04-14T10:47:28.949216Z","steps":["trace[211838879] 'process raft request' (duration: 319.64866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.949404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:28.624586Z","time spent":"324.701465ms","remote":"127.0.0.1:39290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:28.949789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.912603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:28.949869Z","caller":"traceutil/trace.go:171","msg":"trace[857034326] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:576; }","duration":"310.997206ms","start":"2026-04-14T10:47:28.638834Z","end":"2026-04-14T10:47:28.949832Z","steps":["trace[857034326] 'agreement among raft nodes before linearized reading' (duration: 310.885153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.949926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:28.638818Z","time spent":"311.089288ms","remote":"127.0.0.1:40584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:47:28.950304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.377265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:28.950369Z","caller":"traceutil/trace.go:171","msg":"trace[1929705622] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:576; }","duration":"311.442026ms","start":"2026-04-14T10:47:28.638898Z","end":"2026-04-14T10:47:28.95034Z","steps":["trace[1929705622] 'agreement among raft nodes before linearized reading' (duration: 311.287633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.950423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:28.638895Z","time spent":"311.512848ms","remote":"127.0.0.1:40584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-14T10:47:28.950956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.277858ms","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-14T10:47:28.950987Z","caller":"traceutil/trace.go:171","msg":"trace[814177687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:576; }","duration":"130.35433ms","start":"2026-04-14T10:47:28.820619Z","end":"2026-04-14T10:47:28.950974Z","steps":["trace[814177687] 'agreement among raft nodes before linearized reading' (duration: 130.314059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.951093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.610206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:28.951124Z","caller":"traceutil/trace.go:171","msg":"trace[197615935] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:576; }","duration":"130.652447ms","start":"2026-04-14T10:47:28.820461Z","end":"2026-04-14T10:47:28.951113Z","steps":["trace[197615935] 'agreement among raft nodes before linearized reading' (duration: 130.607466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.951228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.711493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:28.951258Z","caller":"traceutil/trace.go:171","msg":"trace[1352430039] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:576; }","duration":"175.750024ms","start":"2026-04-14T10:47:28.775497Z","end":"2026-04-14T10:47:28.951247Z","steps":["trace[1352430039] 'agreement among raft nodes before linearized reading' (duration: 175.707073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.951384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.414391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:28.951415Z","caller":"traceutil/trace.go:171","msg":"trace[149583935] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:576; }","duration":"176.454722ms","start":"2026-04-14T10:47:28.774949Z","end":"2026-04-14T10:47:28.951403Z","steps":["trace[149583935] 'agreement among raft nodes before linearized reading' (duration: 176.408691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.95159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.647466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:28.951616Z","caller":"traceutil/trace.go:171","msg":"trace[726959627] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:576; }","duration":"176.708238ms","start":"2026-04-14T10:47:28.774899Z","end":"2026-04-14T10:47:28.951608Z","steps":["trace[726959627] 'agreement among raft nodes before linearized reading' (duration: 176.595375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.952104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.200216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:28.9521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.821385ms","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":"warn","ts":"2026-04-14T10:47:28.952108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.22472ms","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-14T10:47:28.952179Z","caller":"traceutil/trace.go:171","msg":"trace[824316339] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:576; }","duration":"294.946818ms","start":"2026-04-14T10:47:28.657211Z","end":"2026-04-14T10:47:28.952158Z","steps":["trace[824316339] 'agreement among raft nodes before linearized reading' (duration: 294.521867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.952109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.657236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:28.952254Z","caller":"traceutil/trace.go:171","msg":"trace[1578697601] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:576; }","duration":"296.344373ms","start":"2026-04-14T10:47:28.655823Z","end":"2026-04-14T10:47:28.952167Z","steps":["trace[1578697601] 'agreement among raft nodes before linearized reading' (duration: 295.917492ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:28.952176Z","caller":"traceutil/trace.go:171","msg":"trace[1043915435] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:576; }","duration":"312.280437ms","start":"2026-04-14T10:47:28.639869Z","end":"2026-04-14T10:47:28.95215Z","steps":["trace[1043915435] 'agreement among raft nodes before linearized reading' (duration: 311.855837ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:28.952373Z","caller":"traceutil/trace.go:171","msg":"trace[1056339629] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:576; }","duration":"295.947423ms","start":"2026-04-14T10:47:28.656399Z","end":"2026-04-14T10:47:28.952347Z","steps":["trace[1056339629] 'agreement among raft nodes before linearized reading' (duration: 295.337988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:28.952418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:28.639863Z","time spent":"312.537334ms","remote":"127.0.0.1:40584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-14T10:47:29.436616Z","caller":"traceutil/trace.go:171","msg":"trace[2076742714] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"417.946442ms","start":"2026-04-14T10:47:29.018644Z","end":"2026-04-14T10:47:29.43659Z","steps":["trace[2076742714] 'read index received' (duration: 417.810409ms)","trace[2076742714] 'applied index is now lower than readState.Index' (duration: 135.173µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:29.436683Z","caller":"traceutil/trace.go:171","msg":"trace[356136988] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"441.748083ms","start":"2026-04-14T10:47:28.994915Z","end":"2026-04-14T10:47:29.436663Z","steps":["trace[356136988] 'process raft request' (duration: 441.550278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.436888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.210539ms","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":"warn","ts":"2026-04-14T10:47:29.436929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.102666ms","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":"warn","ts":"2026-04-14T10:47:29.436952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:28.994891Z","time spent":"441.846715ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:29.436967Z","caller":"traceutil/trace.go:171","msg":"trace[434105713] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:577; }","duration":"418.334251ms","start":"2026-04-14T10:47:29.018615Z","end":"2026-04-14T10:47:29.436949Z","steps":["trace[434105713] 'agreement among raft nodes before linearized reading' (duration: 418.117696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.436953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.556128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.437014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.018601Z","time spent":"418.405833ms","remote":"127.0.0.1:40622","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":"info","ts":"2026-04-14T10:47:29.437035Z","caller":"traceutil/trace.go:171","msg":"trace[398705281] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:577; }","duration":"192.722802ms","start":"2026-04-14T10:47:29.244292Z","end":"2026-04-14T10:47:29.437015Z","steps":["trace[398705281] 'agreement among raft nodes before linearized reading' (duration: 192.581028ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.437001Z","caller":"traceutil/trace.go:171","msg":"trace[537041882] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:577; }","duration":"418.2531ms","start":"2026-04-14T10:47:29.018718Z","end":"2026-04-14T10:47:29.436971Z","steps":["trace[537041882] 'agreement among raft nodes before linearized reading' (duration: 418.046414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.437255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.990038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.437329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.438303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.437344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.938785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:29.437354Z","caller":"traceutil/trace.go:171","msg":"trace[1254188594] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:577; }","duration":"241.484664ms","start":"2026-04-14T10:47:29.195862Z","end":"2026-04-14T10:47:29.437347Z","steps":["trace[1254188594] 'agreement among raft nodes before linearized reading' (duration: 241.442413ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.437352Z","caller":"traceutil/trace.go:171","msg":"trace[756039679] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:577; }","duration":"193.07822ms","start":"2026-04-14T10:47:29.244252Z","end":"2026-04-14T10:47:29.437331Z","steps":["trace[756039679] 'agreement among raft nodes before linearized reading' (duration: 192.945657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.437413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.04011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.437424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.278855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:29.43744Z","caller":"traceutil/trace.go:171","msg":"trace[1152266111] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:577; }","duration":"154.076211ms","start":"2026-04-14T10:47:29.283357Z","end":"2026-04-14T10:47:29.437433Z","steps":["trace[1152266111] 'agreement among raft nodes before linearized reading' (duration: 154.03741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.437456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.197017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.437452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.446211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:29.437516Z","caller":"traceutil/trace.go:171","msg":"trace[1047653402] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:577; }","duration":"292.537434ms","start":"2026-04-14T10:47:29.144962Z","end":"2026-04-14T10:47:29.4375Z","steps":["trace[1047653402] 'agreement among raft nodes before linearized reading' (duration: 292.449321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.437575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.561962ms","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":"warn","ts":"2026-04-14T10:47:29.437615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.275625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:29.43762Z","caller":"traceutil/trace.go:171","msg":"trace[217947206] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:577; }","duration":"310.619673ms","start":"2026-04-14T10:47:29.126988Z","end":"2026-04-14T10:47:29.437608Z","steps":["trace[217947206] 'agreement among raft nodes before linearized reading' (duration: 310.50151ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.437636Z","caller":"traceutil/trace.go:171","msg":"trace[1270550289] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:577; }","duration":"154.294596ms","start":"2026-04-14T10:47:29.283335Z","end":"2026-04-14T10:47:29.437629Z","steps":["trace[1270550289] 'agreement among raft nodes before linearized reading' (duration: 154.266195ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.437374Z","caller":"traceutil/trace.go:171","msg":"trace[631075031] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:577; }","duration":"136.985396ms","start":"2026-04-14T10:47:29.30038Z","end":"2026-04-14T10:47:29.437365Z","steps":["trace[631075031] 'agreement among raft nodes before linearized reading' (duration: 136.944195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.437666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.126981Z","time spent":"310.671144ms","remote":"127.0.0.1:40622","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:500 "} {"level":"warn","ts":"2026-04-14T10:47:29.43774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.019899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.437762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.191311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:29.437783Z","caller":"traceutil/trace.go:171","msg":"trace[1833298544] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:577; }","duration":"176.231542ms","start":"2026-04-14T10:47:29.261546Z","end":"2026-04-14T10:47:29.437778Z","steps":["trace[1833298544] 'agreement among raft nodes before linearized reading' (duration: 176.200071ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.437781Z","caller":"traceutil/trace.go:171","msg":"trace[2085239336] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:577; }","duration":"193.143442ms","start":"2026-04-14T10:47:29.244626Z","end":"2026-04-14T10:47:29.437769Z","steps":["trace[2085239336] 'agreement among raft nodes before linearized reading' (duration: 193.03123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.437826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.043198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.437261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.107824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.437238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.018715Z","time spent":"418.497365ms","remote":"127.0.0.1:40622","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-14T10:47:29.437964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.272084ms","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-14T10:47:29.437466Z","caller":"traceutil/trace.go:171","msg":"trace[156650750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"310.333186ms","start":"2026-04-14T10:47:29.127119Z","end":"2026-04-14T10:47:29.437452Z","steps":["trace[156650750] 'agreement among raft nodes before linearized reading' (duration: 310.281605ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.438009Z","caller":"traceutil/trace.go:171","msg":"trace[493876081] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:577; }","duration":"312.322625ms","start":"2026-04-14T10:47:29.125674Z","end":"2026-04-14T10:47:29.437997Z","steps":["trace[493876081] 'agreement among raft nodes before linearized reading' (duration: 312.209872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.438021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.12711Z","time spent":"310.90222ms","remote":"127.0.0.1:39184","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-14T10:47:29.437585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.407317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.438051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.12559Z","time spent":"312.450789ms","remote":"127.0.0.1:40638","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-14T10:47:29.438066Z","caller":"traceutil/trace.go:171","msg":"trace[1184129022] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:577; }","duration":"176.904338ms","start":"2026-04-14T10:47:29.261153Z","end":"2026-04-14T10:47:29.438057Z","steps":["trace[1184129022] 'agreement among raft nodes before linearized reading' (duration: 176.405646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.437372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.385444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.438153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.87608ms","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-14T10:47:29.438172Z","caller":"traceutil/trace.go:171","msg":"trace[1842187093] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:577; }","duration":"312.91601ms","start":"2026-04-14T10:47:29.12525Z","end":"2026-04-14T10:47:29.438166Z","steps":["trace[1842187093] 'agreement among raft nodes before linearized reading' (duration: 312.883479ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.438172Z","caller":"traceutil/trace.go:171","msg":"trace[107125071] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:577; }","duration":"294.206215ms","start":"2026-04-14T10:47:29.143953Z","end":"2026-04-14T10:47:29.438159Z","steps":["trace[107125071] 'agreement among raft nodes before linearized reading' (duration: 293.369164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.438191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.125217Z","time spent":"312.968672ms","remote":"127.0.0.1:40638","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-14T10:47:29.437304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.835493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:29.438254Z","caller":"traceutil/trace.go:171","msg":"trace[138942186] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:577; }","duration":"137.803266ms","start":"2026-04-14T10:47:29.300436Z","end":"2026-04-14T10:47:29.43824Z","steps":["trace[138942186] 'agreement among raft nodes before linearized reading' (duration: 136.848242ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.437478Z","caller":"traceutil/trace.go:171","msg":"trace[633281557] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:577; }","duration":"241.262899ms","start":"2026-04-14T10:47:29.196209Z","end":"2026-04-14T10:47:29.437472Z","steps":["trace[633281557] 'agreement among raft nodes before linearized reading' (duration: 241.230908ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.437872Z","caller":"traceutil/trace.go:171","msg":"trace[1664975891] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:577; }","duration":"136.73465ms","start":"2026-04-14T10:47:29.301131Z","end":"2026-04-14T10:47:29.437866Z","steps":["trace[1664975891] 'agreement among raft nodes before linearized reading' (duration: 136.101894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:29.437133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.012176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:47:29.437633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.506169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:29.438427Z","caller":"traceutil/trace.go:171","msg":"trace[350182463] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:577; }","duration":"294.378879ms","start":"2026-04-14T10:47:29.144034Z","end":"2026-04-14T10:47:29.438413Z","steps":["trace[350182463] 'agreement among raft nodes before linearized reading' (duration: 292.900823ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.437868Z","caller":"traceutil/trace.go:171","msg":"trace[1346610434] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:577; }","duration":"242.115589ms","start":"2026-04-14T10:47:29.195741Z","end":"2026-04-14T10:47:29.437856Z","steps":["trace[1346610434] 'agreement among raft nodes before linearized reading' (duration: 242.047658ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:29.438469Z","caller":"traceutil/trace.go:171","msg":"trace[1922523919] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:577; }","duration":"177.35609ms","start":"2026-04-14T10:47:29.261097Z","end":"2026-04-14T10:47:29.438453Z","steps":["trace[1922523919] 'agreement among raft nodes before linearized reading' (duration: 176.508799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:30.357663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:30.85876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:31.359133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:31.859832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678500,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:31.982161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.128617185s","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-14T10:47:31.982256Z","caller":"traceutil/trace.go:171","msg":"trace[1436331333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"2.128750108s","start":"2026-04-14T10:47:29.853485Z","end":"2026-04-14T10:47:31.982235Z","steps":["trace[1436331333] 'range keys from in-memory index tree' (duration: 2.1284138s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:31.982327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.853467Z","time spent":"2.12882725s","remote":"127.0.0.1:39470","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-14T10:47:32.035742Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.178781144s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T10:47:32.03791Z","caller":"traceutil/trace.go:171","msg":"trace[1367793111] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"2.182463786s","start":"2026-04-14T10:47:29.855421Z","end":"2026-04-14T10:47:32.037885Z","steps":["trace[1367793111] 'process raft request' (duration: 2.180818205s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:32.038067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.855403Z","time spent":"2.182584449s","remote":"127.0.0.1:39290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:32.039796Z","caller":"traceutil/trace.go:171","msg":"trace[1290453771] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"2.182730783s","start":"2026-04-14T10:47:29.857043Z","end":"2026-04-14T10:47:32.039774Z","steps":["trace[1290453771] 'read index received' (duration: 2.178986849s)","trace[1290453771] 'applied index is now lower than readState.Index' (duration: 3.742954ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:32.040184Z","caller":"traceutil/trace.go:171","msg":"trace[1774528048] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"185.397894ms","start":"2026-04-14T10:47:31.854771Z","end":"2026-04-14T10:47:32.040169Z","steps":["trace[1774528048] 'process raft request' (duration: 184.739587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:32.04068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.183621645s","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-14T10:47:32.040732Z","caller":"traceutil/trace.go:171","msg":"trace[1712410895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"2.183706218s","start":"2026-04-14T10:47:29.857011Z","end":"2026-04-14T10:47:32.040717Z","steps":["trace[1712410895] 'agreement among raft nodes before linearized reading' (duration: 2.183530793s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:32.040833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.857Z","time spent":"2.1838178s","remote":"127.0.0.1:39470","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-14T10:47:32.040954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.178941409s","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-14T10:47:32.041008Z","caller":"traceutil/trace.go:171","msg":"trace[1785402652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"2.179023401s","start":"2026-04-14T10:47:29.861969Z","end":"2026-04-14T10:47:32.040992Z","steps":["trace[1785402652] 'agreement among raft nodes before linearized reading' (duration: 2.178887967s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:32.04105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.861947Z","time spent":"2.179091803s","remote":"127.0.0.1:39470","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-14T10:47:32.041164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.910673238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:32.041206Z","caller":"traceutil/trace.go:171","msg":"trace[328061947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"1.91075177s","start":"2026-04-14T10:47:30.130442Z","end":"2026-04-14T10:47:32.041193Z","steps":["trace[328061947] 'agreement among raft nodes before linearized reading' (duration: 1.910685488s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:32.041244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:30.130423Z","time spent":"1.910810242s","remote":"127.0.0.1:39184","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-14T10:47:32.041296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.178770195s","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-14T10:47:32.041339Z","caller":"traceutil/trace.go:171","msg":"trace[124887072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"2.178825396s","start":"2026-04-14T10:47:29.862501Z","end":"2026-04-14T10:47:32.041326Z","steps":["trace[124887072] 'agreement among raft nodes before linearized reading' (duration: 2.178704343s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:32.041375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:29.862493Z","time spent":"2.178872587s","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T10:47:32.041553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.155883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.168\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T10:47:32.041603Z","caller":"traceutil/trace.go:171","msg":"trace[1491358813] range","detail":"{range_begin:/registry/masterleases/199.19.213.168; range_end:; response_count:1; response_revision:580; }","duration":"794.234166ms","start":"2026-04-14T10:47:31.247355Z","end":"2026-04-14T10:47:32.041589Z","steps":["trace[1491358813] 'agreement among raft nodes before linearized reading' (duration: 794.059891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:32.041644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:31.247308Z","time spent":"794.323518ms","remote":"127.0.0.1:39232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.168\" "} {"level":"info","ts":"2026-04-14T10:47:33.688955Z","caller":"traceutil/trace.go:171","msg":"trace[921761335] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"266.915255ms","start":"2026-04-14T10:47:33.422015Z","end":"2026-04-14T10:47:33.68893Z","steps":["trace[921761335] 'process raft request' (duration: 226.110907ms)","trace[921761335] 'compare' (duration: 40.696956ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:33.693204Z","caller":"traceutil/trace.go:171","msg":"trace[968950602] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"117.271375ms","start":"2026-04-14T10:47:33.575914Z","end":"2026-04-14T10:47:33.693185Z","steps":["trace[968950602] 'process raft request' (duration: 116.30493ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:34.033157Z","caller":"traceutil/trace.go:171","msg":"trace[209440062] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"113.465731ms","start":"2026-04-14T10:47:33.919664Z","end":"2026-04-14T10:47:34.03313Z","steps":["trace[209440062] 'process raft request' (duration: 112.698752ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:34.489354Z","caller":"traceutil/trace.go:171","msg":"trace[1832440142] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"312.481259ms","start":"2026-04-14T10:47:34.176844Z","end":"2026-04-14T10:47:34.489325Z","steps":["trace[1832440142] 'process raft request' (duration: 261.739576ms)","trace[1832440142] 'compare' (duration: 50.409644ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:34.490115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.176821Z","time spent":"313.159505ms","remote":"127.0.0.1:39290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:35.188528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.12752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:35.1888Z","caller":"traceutil/trace.go:171","msg":"trace[306043491] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"971.937571ms","start":"2026-04-14T10:47:34.216824Z","end":"2026-04-14T10:47:35.188762Z","steps":["trace[306043491] 'process raft request' (duration: 709.478002ms)","trace[306043491] 'compare' (duration: 261.539256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:35.188948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.216795Z","time spent":"972.098605ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:35.189008Z","caller":"traceutil/trace.go:171","msg":"trace[1556272418] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"885.196189ms","start":"2026-04-14T10:47:34.303765Z","end":"2026-04-14T10:47:35.188961Z","steps":["trace[1556272418] 'process raft request' (duration: 884.877411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.189194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.303736Z","time spent":"885.369953ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:35.189236Z","caller":"traceutil/trace.go:171","msg":"trace[233450976] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"879.753681ms","start":"2026-04-14T10:47:34.309428Z","end":"2026-04-14T10:47:35.189182Z","steps":["trace[233450976] 'process raft request' (duration: 879.265819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.189529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.309418Z","time spent":"879.925266ms","remote":"127.0.0.1:39284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:35.189593Z","caller":"traceutil/trace.go:171","msg":"trace[1473705991] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"798.61014ms","start":"2026-04-14T10:47:34.390968Z","end":"2026-04-14T10:47:35.189578Z","steps":["trace[1473705991] 'process raft request' (duration: 797.771899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.189697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.390936Z","time spent":"798.714682ms","remote":"127.0.0.1:39470","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-14T10:47:35.189545Z","caller":"traceutil/trace.go:171","msg":"trace[2073732899] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"885.855925ms","start":"2026-04-14T10:47:34.303669Z","end":"2026-04-14T10:47:35.189525Z","steps":["trace[2073732899] 'read index received' (duration: 134.90397ms)","trace[2073732899] 'applied index is now lower than readState.Index' (duration: 750.950595ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:35.191274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.589469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-kcllx\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-04-14T10:47:35.191325Z","caller":"traceutil/trace.go:171","msg":"trace[8419992] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-kcllx; range_end:; response_count:1; response_revision:629; }","duration":"887.694991ms","start":"2026-04-14T10:47:34.303617Z","end":"2026-04-14T10:47:35.191312Z","steps":["trace[8419992] 'agreement among raft nodes before linearized reading' (duration: 886.719037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.191369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.303598Z","time spent":"887.760604ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4706,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-kcllx\" "} {"level":"warn","ts":"2026-04-14T10:47:35.287857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.849091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:35.287955Z","caller":"traceutil/trace.go:171","msg":"trace[2022923860] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"790.995245ms","start":"2026-04-14T10:47:34.496939Z","end":"2026-04-14T10:47:35.287934Z","steps":["trace[2022923860] 'agreement among raft nodes before linearized reading' (duration: 790.837171ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:35.287946Z","caller":"traceutil/trace.go:171","msg":"trace[157569584] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"387.834125ms","start":"2026-04-14T10:47:34.900071Z","end":"2026-04-14T10:47:35.287905Z","steps":["trace[157569584] 'process raft request' (duration: 387.660021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.288013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.496922Z","time spent":"791.081627ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:35.288093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.900033Z","time spent":"387.990539ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:35.288327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.877505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:35.288482Z","caller":"traceutil/trace.go:171","msg":"trace[1337407339] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"192.307097ms","start":"2026-04-14T10:47:35.096152Z","end":"2026-04-14T10:47:35.288459Z","steps":["trace[1337407339] 'agreement among raft nodes before linearized reading' (duration: 191.851085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.28857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.493428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:35.288653Z","caller":"traceutil/trace.go:171","msg":"trace[341420092] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"592.622421ms","start":"2026-04-14T10:47:34.696011Z","end":"2026-04-14T10:47:35.288634Z","steps":["trace[341420092] 'agreement among raft nodes before linearized reading' (duration: 592.486057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.288696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.69599Z","time spent":"592.697912ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:35.288708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.458154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:35.28875Z","caller":"traceutil/trace.go:171","msg":"trace[1184231070] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"742.52162ms","start":"2026-04-14T10:47:34.545441Z","end":"2026-04-14T10:47:35.287963Z","steps":["trace[1184231070] 'process raft request' (duration: 742.165451ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:35.28883Z","caller":"traceutil/trace.go:171","msg":"trace[281616097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"161.649429ms","start":"2026-04-14T10:47:35.12715Z","end":"2026-04-14T10:47:35.288799Z","steps":["trace[281616097] 'agreement among raft nodes before linearized reading' (duration: 161.408593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.288925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:34.545414Z","time spent":"743.429502ms","remote":"127.0.0.1:39682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:35.850385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.962018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:35.850606Z","caller":"traceutil/trace.go:171","msg":"trace[256719951] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"559.907317ms","start":"2026-04-14T10:47:35.290682Z","end":"2026-04-14T10:47:35.85059Z","steps":["trace[256719951] 'read index received' (duration: 419.601349ms)","trace[256719951] 'applied index is now lower than readState.Index' (duration: 140.304978ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:35.850879Z","caller":"traceutil/trace.go:171","msg":"trace[1909323940] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"651.830958ms","start":"2026-04-14T10:47:35.199035Z","end":"2026-04-14T10:47:35.850866Z","steps":["trace[1909323940] 'process raft request' (duration: 511.258494ms)","trace[1909323940] 'compare' (duration: 139.844316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:35.850947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.199011Z","time spent":"651.901819ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4873,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:35.850934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.233795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:35.851005Z","caller":"traceutil/trace.go:171","msg":"trace[1064243343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"560.355248ms","start":"2026-04-14T10:47:35.290634Z","end":"2026-04-14T10:47:35.850989Z","steps":["trace[1064243343] 'agreement among raft nodes before linearized reading' (duration: 560.227925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.851042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.290613Z","time spent":"560.42346ms","remote":"127.0.0.1:39170","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-14T10:47:35.851202Z","caller":"traceutil/trace.go:171","msg":"trace[1711254185] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"648.463742ms","start":"2026-04-14T10:47:35.202728Z","end":"2026-04-14T10:47:35.851192Z","steps":["trace[1711254185] 'process raft request' (duration: 647.788825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.851226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.907388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-14T10:47:35.851257Z","caller":"traceutil/trace.go:171","msg":"trace[546065127] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:633; }","duration":"154.97683ms","start":"2026-04-14T10:47:35.69627Z","end":"2026-04-14T10:47:35.851246Z","steps":["trace[546065127] 'agreement among raft nodes before linearized reading' (duration: 154.861257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.8513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.202711Z","time spent":"648.515044ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:35.851352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.386081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-14T10:47:35.851419Z","caller":"traceutil/trace.go:171","msg":"trace[868951168] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:633; }","duration":"355.505694ms","start":"2026-04-14T10:47:35.495903Z","end":"2026-04-14T10:47:35.851409Z","steps":["trace[868951168] 'agreement among raft nodes before linearized reading' (duration: 355.382721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.851426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.236104ms","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-14T10:47:35.85147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.495874Z","time spent":"355.566756ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-04-14T10:47:35.851481Z","caller":"traceutil/trace.go:171","msg":"trace[955872222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:633; }","duration":"136.327006ms","start":"2026-04-14T10:47:35.715138Z","end":"2026-04-14T10:47:35.851464Z","steps":["trace[955872222] 'agreement among raft nodes before linearized reading' (duration: 136.177392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.851195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.454504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:35.851615Z","caller":"traceutil/trace.go:171","msg":"trace[1132881488] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:633; }","duration":"555.933737ms","start":"2026-04-14T10:47:35.295663Z","end":"2026-04-14T10:47:35.851597Z","steps":["trace[1132881488] 'agreement among raft nodes before linearized reading' (duration: 555.470105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.851739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.295645Z","time spent":"556.07942ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:35.851515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.804661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:35.851867Z","caller":"traceutil/trace.go:171","msg":"trace[128288180] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:633; }","duration":"550.15972ms","start":"2026-04-14T10:47:35.301657Z","end":"2026-04-14T10:47:35.851817Z","steps":["trace[128288180] 'agreement among raft nodes before linearized reading' (duration: 549.77054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:35.851935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.30165Z","time spent":"550.269523ms","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-14T10:47:36.351301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.172883ms","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-14T10:47:36.351438Z","caller":"traceutil/trace.go:171","msg":"trace[2037634873] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:633; }","duration":"298.323687ms","start":"2026-04-14T10:47:36.053096Z","end":"2026-04-14T10:47:36.35142Z","steps":["trace[2037634873] 'range keys from in-memory index tree' (duration: 298.156663ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:36.35166Z","caller":"traceutil/trace.go:171","msg":"trace[68176538] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"495.182423ms","start":"2026-04-14T10:47:35.856465Z","end":"2026-04-14T10:47:36.351648Z","steps":["trace[68176538] 'process raft request' (duration: 468.544996ms)","trace[68176538] 'compare' (duration: 25.942439ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:36.351924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.85643Z","time spent":"495.393418ms","remote":"127.0.0.1:39470","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-14T10:47:37.8203Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.455751027s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T10:47:37.820756Z","caller":"traceutil/trace.go:171","msg":"trace[506852460] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.957071657s","start":"2026-04-14T10:47:35.863661Z","end":"2026-04-14T10:47:37.820733Z","steps":["trace[506852460] 'process raft request' (duration: 1.956869472s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:37.820917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.863633Z","time spent":"1.95721322s","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:37.821216Z","caller":"traceutil/trace.go:171","msg":"trace[1108446812] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.955825445s","start":"2026-04-14T10:47:35.865368Z","end":"2026-04-14T10:47:37.821194Z","steps":["trace[1108446812] 'process raft request' (duration: 1.955311792s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:37.821404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.865344Z","time spent":"1.955981589s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:37.821453Z","caller":"traceutil/trace.go:171","msg":"trace[203256279] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"1.925456455s","start":"2026-04-14T10:47:35.895984Z","end":"2026-04-14T10:47:37.821441Z","steps":["trace[203256279] 'read index received' (duration: 429.119866ms)","trace[203256279] 'applied index is now lower than readState.Index' (duration: 1.496335789s)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:37.821525Z","caller":"traceutil/trace.go:171","msg":"trace[292491102] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.950683445s","start":"2026-04-14T10:47:35.870834Z","end":"2026-04-14T10:47:37.821517Z","steps":["trace[292491102] 'process raft request' (duration: 1.950268454s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:37.821587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.870806Z","time spent":"1.950746207s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:37.821774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925781243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-14T10:47:37.821828Z","caller":"traceutil/trace.go:171","msg":"trace[1483851135] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:637; }","duration":"1.925868836s","start":"2026-04-14T10:47:35.895946Z","end":"2026-04-14T10:47:37.821815Z","steps":["trace[1483851135] 'agreement among raft nodes before linearized reading' (duration: 1.925717472s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:37.821863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:35.895931Z","time spent":"1.925923598s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:38.128894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000246009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-14T10:47:38.129208Z","caller":"traceutil/trace.go:171","msg":"trace[1720378975] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000606347s","start":"2026-04-14T10:47:36.128581Z","end":"2026-04-14T10:47:38.129187Z","steps":["trace[1720378975] 'agreement among raft nodes before linearized reading' (duration: 2.000268939s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:38.129312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.128568Z","time spent":"2.000729681s","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/14 10:47:38 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-14T10:47:38.322173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678606,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T10:47:38.360268Z","caller":"traceutil/trace.go:171","msg":"trace[41508707] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.861655009s","start":"2026-04-14T10:47:36.498588Z","end":"2026-04-14T10:47:38.360243Z","steps":["trace[41508707] 'process raft request' (duration: 1.861599607s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:38.360308Z","caller":"traceutil/trace.go:171","msg":"trace[1262409433] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.124233457s","start":"2026-04-14T10:47:36.236048Z","end":"2026-04-14T10:47:38.360282Z","steps":["trace[1262409433] 'process raft request' (duration: 2.120923062s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:38.360403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.498551Z","time spent":"1.861804243s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:38.360459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.236022Z","time spent":"2.124365601s","remote":"127.0.0.1:39290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:39.385168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999883198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-14T10:47:39.385249Z","caller":"traceutil/trace.go:171","msg":"trace[1331799130] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000026952s","start":"2026-04-14T10:47:37.385206Z","end":"2026-04-14T10:47:39.385233Z","steps":["trace[1331799130] 'agreement among raft nodes before linearized reading' (duration: 1.999916629s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.385408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:37.385186Z","time spent":"2.000092943s","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T10:47:39.785171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283786924s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:39.785865Z","caller":"traceutil/trace.go:171","msg":"trace[1828263386] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"3.087075469s","start":"2026-04-14T10:47:36.698757Z","end":"2026-04-14T10:47:39.785833Z","steps":["trace[1828263386] 'process raft request' (duration: 1.802532166s)","trace[1828263386] 'compare' (duration: 1.28326042s)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:39.785991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.698729Z","time spent":"3.087202411s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:39.786122Z","caller":"traceutil/trace.go:171","msg":"trace[1883661892] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.860367483s","start":"2026-04-14T10:47:36.925745Z","end":"2026-04-14T10:47:39.786113Z","steps":["trace[1883661892] 'process raft request' (duration: 2.859627744s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:39.78616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.92571Z","time spent":"2.860431605s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:40.129622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000103004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-14T10:47:40.129706Z","caller":"traceutil/trace.go:171","msg":"trace[1021565747] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000289639s","start":"2026-04-14T10:47:38.129401Z","end":"2026-04-14T10:47:40.129691Z","steps":["trace[1021565747] 'agreement among raft nodes before linearized reading' (duration: 2.000178606s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.129753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.129382Z","time spent":"2.000362091s","remote":"127.0.0.1:39170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T10:47:40.250951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.41248ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T10:47:40.251545Z","caller":"traceutil/trace.go:171","msg":"trace[955150670] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.729781965s","start":"2026-04-14T10:47:37.521719Z","end":"2026-04-14T10:47:40.251501Z","steps":["trace[955150670] 'process raft request' (duration: 2.729452247s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.251697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:37.521695Z","time spent":"2.729927569s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:40.251817Z","caller":"traceutil/trace.go:171","msg":"trace[1261147657] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"2.419211212s","start":"2026-04-14T10:47:37.832595Z","end":"2026-04-14T10:47:40.251806Z","steps":["trace[1261147657] 'process raft request' (duration: 2.418998997s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.251883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:37.83258Z","time spent":"2.419257014s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:40.251915Z","caller":"traceutil/trace.go:171","msg":"trace[537217772] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.88435063s","start":"2026-04-14T10:47:38.36753Z","end":"2026-04-14T10:47:40.251881Z","steps":["trace[537217772] 'process raft request' (duration: 1.884137695s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:40.251969Z","caller":"traceutil/trace.go:171","msg":"trace[1559509561] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:664; }","duration":"2.430428639s","start":"2026-04-14T10:47:37.821489Z","end":"2026-04-14T10:47:40.251918Z","steps":["trace[1559509561] 'read index received' (duration: 531.503195ms)","trace[1559509561] 'applied index is now lower than readState.Index' (duration: 1.898923614s)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:40.252083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.367501Z","time spent":"1.884488404s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:40.252099Z","caller":"traceutil/trace.go:171","msg":"trace[186591705] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.420588128s","start":"2026-04-14T10:47:37.831501Z","end":"2026-04-14T10:47:40.25209Z","steps":["trace[186591705] 'process raft request' (duration: 2.41989668s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.161522799s","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-14T10:47:40.252246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:37.831483Z","time spent":"2.420732772s","remote":"127.0.0.1:39656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4387,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:40.252266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.156962053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-04-14T10:47:40.252236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.159776694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T10:47:40.252277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.956254578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-04-14T10:47:40.252355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.956653282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-14T10:47:40.252383Z","caller":"traceutil/trace.go:171","msg":"trace[635105178] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:647; }","duration":"2.956727235s","start":"2026-04-14T10:47:37.29565Z","end":"2026-04-14T10:47:40.252377Z","steps":["trace[635105178] 'agreement among raft nodes before linearized reading' (duration: 2.956658833s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.156954393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-14T10:47:40.252398Z","caller":"traceutil/trace.go:171","msg":"trace[300806815] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.840255803s","start":"2026-04-14T10:47:38.412129Z","end":"2026-04-14T10:47:40.252385Z","steps":["trace[300806815] 'process raft request' (duration: 1.839588856s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:37.295629Z","time spent":"2.956778056s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-04-14T10:47:40.25244Z","caller":"traceutil/trace.go:171","msg":"trace[132849953] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:647; }","duration":"4.15726226s","start":"2026-04-14T10:47:36.095161Z","end":"2026-04-14T10:47:40.252423Z","steps":["trace[132849953] 'agreement among raft nodes before linearized reading' (duration: 4.156806888s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.159921979s","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-14T10:47:40.252479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.412103Z","time spent":"1.840326525s","remote":"127.0.0.1:39448","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-14T10:47:40.252492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.095151Z","time spent":"4.157329853s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:40.25251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.387013067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10368"} {"level":"warn","ts":"2026-04-14T10:47:40.252507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.427421512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n6g5n\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-04-14T10:47:40.252532Z","caller":"traceutil/trace.go:171","msg":"trace[1081066278] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:647; }","duration":"1.387074549s","start":"2026-04-14T10:47:38.86545Z","end":"2026-04-14T10:47:40.252525Z","steps":["trace[1081066278] 'agreement among raft nodes before linearized reading' (duration: 1.387025007s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.865434Z","time spent":"1.38711367s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10391,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-14T10:47:40.252556Z","caller":"traceutil/trace.go:171","msg":"trace[863591840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"4.160020871s","start":"2026-04-14T10:47:36.092479Z","end":"2026-04-14T10:47:40.2525Z","steps":["trace[863591840] 'agreement among raft nodes before linearized reading' (duration: 4.159765364s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:40.25237Z","caller":"traceutil/trace.go:171","msg":"trace[2062315113] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:647; }","duration":"3.95633727s","start":"2026-04-14T10:47:36.295981Z","end":"2026-04-14T10:47:40.252318Z","steps":["trace[2062315113] 'agreement among raft nodes before linearized reading' (duration: 3.956228258s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.763123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:40.252642Z","caller":"traceutil/trace.go:171","msg":"trace[1131727189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"114.821775ms","start":"2026-04-14T10:47:40.137814Z","end":"2026-04-14T10:47:40.252636Z","steps":["trace[1131727189] 'agreement among raft nodes before linearized reading' (duration: 114.793164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.295966Z","time spent":"3.956663608s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:40.252643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.893497145s","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-14T10:47:40.252489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.193115153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-14T10:47:40.252618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.092456Z","time spent":"4.160156104s","remote":"127.0.0.1:39470","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-14T10:47:40.252678Z","caller":"traceutil/trace.go:171","msg":"trace[768540343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:647; }","duration":"1.893532905s","start":"2026-04-14T10:47:38.359136Z","end":"2026-04-14T10:47:40.252669Z","steps":["trace[768540343] 'agreement among raft nodes before linearized reading' (duration: 1.893434173s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:40.252691Z","caller":"traceutil/trace.go:171","msg":"trace[72277923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"4.19338185s","start":"2026-04-14T10:47:36.059295Z","end":"2026-04-14T10:47:40.252677Z","steps":["trace[72277923] 'agreement among raft nodes before linearized reading' (duration: 4.19300031s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:40.252236Z","caller":"traceutil/trace.go:171","msg":"trace[1499288244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:647; }","duration":"4.16158888s","start":"2026-04-14T10:47:36.090634Z","end":"2026-04-14T10:47:40.252223Z","steps":["trace[1499288244] 'agreement among raft nodes before linearized reading' (duration: 4.161418366s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.059276Z","time spent":"4.193444521s","remote":"127.0.0.1:39470","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-14T10:47:40.252549Z","caller":"traceutil/trace.go:171","msg":"trace[2092185982] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n6g5n; range_end:; response_count:1; response_revision:647; }","duration":"2.427491344s","start":"2026-04-14T10:47:37.825047Z","end":"2026-04-14T10:47:40.252538Z","steps":["trace[2092185982] 'agreement among raft nodes before linearized reading' (duration: 2.427378571s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:40.252294Z","caller":"traceutil/trace.go:171","msg":"trace[177192249] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:647; }","duration":"3.157020714s","start":"2026-04-14T10:47:37.095265Z","end":"2026-04-14T10:47:40.252286Z","steps":["trace[177192249] 'agreement among raft nodes before linearized reading' (duration: 3.156924731s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:37.825022Z","time spent":"2.427741311s","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17547,"request content":"key:\"/registry/pods/kube-system/cilium-n6g5n\" "} {"level":"warn","ts":"2026-04-14T10:47:40.252786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:37.095252Z","time spent":"3.157525427s","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-04-14T10:47:40.252323Z","caller":"traceutil/trace.go:171","msg":"trace[311122345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"4.159896347s","start":"2026-04-14T10:47:36.092405Z","end":"2026-04-14T10:47:40.252302Z","steps":["trace[311122345] 'agreement among raft nodes before linearized reading' (duration: 4.159588629s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.252848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.092395Z","time spent":"4.160445212s","remote":"127.0.0.1:39470","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-14T10:47:40.252733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.359097Z","time spent":"1.893626918s","remote":"127.0.0.1:39470","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-14T10:47:40.252748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:36.090624Z","time spent":"4.162117734s","remote":"127.0.0.1:39470","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-14T10:47:40.251849Z","caller":"traceutil/trace.go:171","msg":"trace[331686972] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.821056032s","start":"2026-04-14T10:47:38.430769Z","end":"2026-04-14T10:47:40.251825Z","steps":["trace[331686972] 'process raft request' (duration: 1.8210029s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.253482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:38.430744Z","time spent":"1.822488809s","remote":"127.0.0.1:39284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:40.822583Z","caller":"traceutil/trace.go:171","msg":"trace[1291575317] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"559.476626ms","start":"2026-04-14T10:47:40.263081Z","end":"2026-04-14T10:47:40.822558Z","steps":["trace[1291575317] 'process raft request' (duration: 559.319742ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:40.822645Z","caller":"traceutil/trace.go:171","msg":"trace[1626964672] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"559.400594ms","start":"2026-04-14T10:47:40.263223Z","end":"2026-04-14T10:47:40.822623Z","steps":["trace[1626964672] 'process raft request' (duration: 559.2276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.822723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.263073Z","time spent":"559.578979ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:40.82258Z","caller":"traceutil/trace.go:171","msg":"trace[188206464] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"562.073093ms","start":"2026-04-14T10:47:40.260476Z","end":"2026-04-14T10:47:40.822549Z","steps":["trace[188206464] 'process raft request' (duration: 561.818267ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:40.822785Z","caller":"traceutil/trace.go:171","msg":"trace[45432949] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"553.247966ms","start":"2026-04-14T10:47:40.26952Z","end":"2026-04-14T10:47:40.822768Z","steps":["trace[45432949] 'process raft request' (duration: 553.00147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.822899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.26047Z","time spent":"562.368361ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:40.822793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.263215Z","time spent":"559.500317ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:40.822661Z","caller":"traceutil/trace.go:171","msg":"trace[417728147] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"562.241647ms","start":"2026-04-14T10:47:40.260403Z","end":"2026-04-14T10:47:40.822645Z","steps":["trace[417728147] 'process raft request' (duration: 495.690224ms)","trace[417728147] 'compare' (duration: 65.991978ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:40.822994Z","caller":"traceutil/trace.go:171","msg":"trace[983985089] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"553.546793ms","start":"2026-04-14T10:47:40.269432Z","end":"2026-04-14T10:47:40.822978Z","steps":["trace[983985089] 'process raft request' (duration: 553.050731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.822995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.269499Z","time spent":"553.38046ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17775,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:40.82308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.269402Z","time spent":"553.638386ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:40.823093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.260383Z","time spent":"562.615887ms","remote":"127.0.0.1:39470","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-14T10:47:40.823168Z","caller":"traceutil/trace.go:171","msg":"trace[1597575152] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"553.453392ms","start":"2026-04-14T10:47:40.269694Z","end":"2026-04-14T10:47:40.823147Z","steps":["trace[1597575152] 'process raft request' (duration: 552.882657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.823342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.269687Z","time spent":"553.573484ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:40.823454Z","caller":"traceutil/trace.go:171","msg":"trace[1016126833] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:675; }","duration":"562.840892ms","start":"2026-04-14T10:47:40.260601Z","end":"2026-04-14T10:47:40.823442Z","steps":["trace[1016126833] 'read index received' (duration: 63.299538ms)","trace[1016126833] 'applied index is now lower than readState.Index' (duration: 499.540504ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:40.823457Z","caller":"traceutil/trace.go:171","msg":"trace[1246885459] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"514.618632ms","start":"2026-04-14T10:47:40.308822Z","end":"2026-04-14T10:47:40.823441Z","steps":["trace[1246885459] 'process raft request' (duration: 514.499959ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:40.823581Z","caller":"traceutil/trace.go:171","msg":"trace[1154872598] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"553.668336ms","start":"2026-04-14T10:47:40.269902Z","end":"2026-04-14T10:47:40.82357Z","steps":["trace[1154872598] 'process raft request' (duration: 553.005629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.823643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.269874Z","time spent":"553.735538ms","remote":"127.0.0.1:39470","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-14T10:47:40.823644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.308796Z","time spent":"514.763795ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:40.824625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.399572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9311"} {"level":"info","ts":"2026-04-14T10:47:40.828358Z","caller":"traceutil/trace.go:171","msg":"trace[1589518733] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"533.109138ms","start":"2026-04-14T10:47:40.295211Z","end":"2026-04-14T10:47:40.82832Z","steps":["trace[1589518733] 'agreement among raft nodes before linearized reading' (duration: 529.349211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.828468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.295151Z","time spent":"533.299503ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9334,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:40.825078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.457435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-14T10:47:40.828703Z","caller":"traceutil/trace.go:171","msg":"trace[307052425] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:658; }","duration":"568.102978ms","start":"2026-04-14T10:47:40.260575Z","end":"2026-04-14T10:47:40.828678Z","steps":["trace[307052425] 'agreement among raft nodes before linearized reading' (duration: 562.952775ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:40.825211Z","caller":"traceutil/trace.go:171","msg":"trace[1224599416] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"495.587122ms","start":"2026-04-14T10:47:40.329612Z","end":"2026-04-14T10:47:40.825199Z","steps":["trace[1224599416] 'process raft request' (duration: 493.775526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.829028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.329591Z","time spent":"499.39364ms","remote":"127.0.0.1:39284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T10:47:40.82549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.266224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-04-14T10:47:40.82906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.260569Z","time spent":"568.346184ms","remote":"127.0.0.1:39422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-04-14T10:47:40.829156Z","caller":"traceutil/trace.go:171","msg":"trace[830437565] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"463.949288ms","start":"2026-04-14T10:47:40.36519Z","end":"2026-04-14T10:47:40.829139Z","steps":["trace[830437565] 'agreement among raft nodes before linearized reading' (duration: 460.272993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.82921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.365174Z","time spent":"464.01993ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:40.825586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.070782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:187261"} {"level":"info","ts":"2026-04-14T10:47:40.829352Z","caller":"traceutil/trace.go:171","msg":"trace[63050038] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:658; }","duration":"516.848879ms","start":"2026-04-14T10:47:40.312493Z","end":"2026-04-14T10:47:40.829342Z","steps":["trace[63050038] 'agreement among raft nodes before linearized reading' (duration: 513.01504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:40.829382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.312483Z","time spent":"516.89156ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":187284,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-14T10:47:41.30534Z","caller":"traceutil/trace.go:171","msg":"trace[1928673612] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"470.316224ms","start":"2026-04-14T10:47:40.835003Z","end":"2026-04-14T10:47:41.305319Z","steps":["trace[1928673612] 'process raft request' (duration: 470.18209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:41.305485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.834995Z","time spent":"470.416266ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:41.647566Z","caller":"traceutil/trace.go:171","msg":"trace[1492950804] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"808.300785ms","start":"2026-04-14T10:47:40.839236Z","end":"2026-04-14T10:47:41.647537Z","steps":["trace[1492950804] 'process raft request' (duration: 808.056109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:41.64768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.839221Z","time spent":"808.406868ms","remote":"127.0.0.1:39284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:41.647708Z","caller":"traceutil/trace.go:171","msg":"trace[755017601] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"807.562846ms","start":"2026-04-14T10:47:40.840124Z","end":"2026-04-14T10:47:41.647687Z","steps":["trace[755017601] 'process raft request' (duration: 807.313069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:41.647806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.840111Z","time spent":"807.644629ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:41.648078Z","caller":"traceutil/trace.go:171","msg":"trace[1517200726] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"774.246009ms","start":"2026-04-14T10:47:40.873791Z","end":"2026-04-14T10:47:41.648037Z","steps":["trace[1517200726] 'process raft request' (duration: 773.746916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:41.648259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.873772Z","time spent":"774.399953ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:41.648044Z","caller":"traceutil/trace.go:171","msg":"trace[1407192930] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:687; }","duration":"806.353205ms","start":"2026-04-14T10:47:40.841619Z","end":"2026-04-14T10:47:41.647972Z","steps":["trace[1407192930] 'read index received' (duration: 463.640672ms)","trace[1407192930] 'applied index is now lower than readState.Index' (duration: 342.710953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:41.648487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.785036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:41.648611Z","caller":"traceutil/trace.go:171","msg":"trace[1861809596] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:663; }","duration":"806.995621ms","start":"2026-04-14T10:47:40.841599Z","end":"2026-04-14T10:47:41.648594Z","steps":["trace[1861809596] 'agreement among raft nodes before linearized reading' (duration: 806.594011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:41.648686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.660549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.168\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-14T10:47:41.648752Z","caller":"traceutil/trace.go:171","msg":"trace[171340829] range","detail":"{range_begin:/registry/masterleases/199.19.213.168; range_end:; response_count:1; response_revision:663; }","duration":"400.798412ms","start":"2026-04-14T10:47:41.247937Z","end":"2026-04-14T10:47:41.648735Z","steps":["trace[171340829] 'agreement among raft nodes before linearized reading' (duration: 400.560156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:41.648795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:41.247911Z","time spent":"400.877394ms","remote":"127.0.0.1:39232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.168\" "} {"level":"warn","ts":"2026-04-14T10:47:41.648718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:40.841593Z","time spent":"807.111835ms","remote":"127.0.0.1:39338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-14T10:47:41.649005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.627922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-04-14T10:47:41.649008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.661204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188243"} {"level":"info","ts":"2026-04-14T10:47:41.649038Z","caller":"traceutil/trace.go:171","msg":"trace[16065726] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:663; }","duration":"356.701593ms","start":"2026-04-14T10:47:41.292327Z","end":"2026-04-14T10:47:41.649029Z","steps":["trace[16065726] 'agreement among raft nodes before linearized reading' (duration: 356.589851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:41.649057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:41.292313Z","time spent":"356.740795ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"info","ts":"2026-04-14T10:47:41.649061Z","caller":"traceutil/trace.go:171","msg":"trace[1812423254] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:663; }","duration":"339.751677ms","start":"2026-04-14T10:47:41.309292Z","end":"2026-04-14T10:47:41.649044Z","steps":["trace[1812423254] 'agreement among raft nodes before linearized reading' (duration: 339.454959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:41.649096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:41.309273Z","time spent":"339.815128ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188266,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:41.649092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.3111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:187470"} {"level":"info","ts":"2026-04-14T10:47:41.649146Z","caller":"traceutil/trace.go:171","msg":"trace[1308545643] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:663; }","duration":"369.391321ms","start":"2026-04-14T10:47:41.279739Z","end":"2026-04-14T10:47:41.64913Z","steps":["trace[1308545643] 'agreement among raft nodes before linearized reading' (duration: 369.209226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:41.649194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:41.279725Z","time spent":"369.459693ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":187493,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-14T10:47:42.036053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.51244ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-14T10:47:42.036231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:41.649716Z","time spent":"386.507705ms","remote":"127.0.0.1:39232","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-14T10:47:42.065547Z","caller":"traceutil/trace.go:171","msg":"trace[1411365934] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"412.260661ms","start":"2026-04-14T10:47:41.653251Z","end":"2026-04-14T10:47:42.065512Z","steps":["trace[1411365934] 'process raft request' (duration: 412.015115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:42.065729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:41.653227Z","time spent":"412.427915ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:47:42.065875Z","caller":"traceutil/trace.go:171","msg":"trace[642398270] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:692; }","duration":"286.225669ms","start":"2026-04-14T10:47:41.779626Z","end":"2026-04-14T10:47:42.065852Z","steps":["trace[642398270] 'read index received' (duration: 285.671125ms)","trace[642398270] 'applied index is now lower than readState.Index' (duration: 553.244µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:47:42.065972Z","caller":"traceutil/trace.go:171","msg":"trace[45542835] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"352.623822ms","start":"2026-04-14T10:47:41.713337Z","end":"2026-04-14T10:47:42.06596Z","steps":["trace[45542835] 'process raft request' (duration: 352.135739ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:42.06604Z","caller":"traceutil/trace.go:171","msg":"trace[1588604173] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"344.171404ms","start":"2026-04-14T10:47:41.72186Z","end":"2026-04-14T10:47:42.066031Z","steps":["trace[1588604173] 'process raft request' (duration: 343.860596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:42.066087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:41.713308Z","time spent":"352.687444ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:42.066104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:41.72185Z","time spent":"344.210216ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:42.066295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.681831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-04-14T10:47:42.066321Z","caller":"traceutil/trace.go:171","msg":"trace[1094386050] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:666; }","duration":"286.752182ms","start":"2026-04-14T10:47:41.779561Z","end":"2026-04-14T10:47:42.066313Z","steps":["trace[1094386050] 'agreement among raft nodes before linearized reading' (duration: 286.598958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:42.07802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.696408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-04-14T10:47:42.078075Z","caller":"traceutil/trace.go:171","msg":"trace[581114723] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:668; }","duration":"268.76158ms","start":"2026-04-14T10:47:41.809301Z","end":"2026-04-14T10:47:42.078063Z","steps":["trace[581114723] 'agreement among raft nodes before linearized reading' (duration: 268.604506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:42.253166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.460004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:42.253274Z","caller":"traceutil/trace.go:171","msg":"trace[721807740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"116.622447ms","start":"2026-04-14T10:47:42.136628Z","end":"2026-04-14T10:47:42.25325Z","steps":["trace[721807740] 'range keys from in-memory index tree' (duration: 116.386682ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:54.550695Z","caller":"traceutil/trace.go:171","msg":"trace[1985255366] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"133.230969ms","start":"2026-04-14T10:47:54.417447Z","end":"2026-04-14T10:47:54.550678Z","steps":["trace[1985255366] 'process raft request' (duration: 133.142577ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:54.682459Z","caller":"traceutil/trace.go:171","msg":"trace[519396588] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"103.125707ms","start":"2026-04-14T10:47:54.579307Z","end":"2026-04-14T10:47:54.682432Z","steps":["trace[519396588] 'process raft request' (duration: 102.701526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:55.402966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:55.903868Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:56.147081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258624374s","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-14T10:47:56.147145Z","caller":"traceutil/trace.go:171","msg":"trace[1000417344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"1.258748677s","start":"2026-04-14T10:47:54.888382Z","end":"2026-04-14T10:47:56.14713Z","steps":["trace[1000417344] 'range keys from in-memory index tree' (duration: 1.258450139s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:56.147177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:54.888354Z","time spent":"1.258815559s","remote":"127.0.0.1:39470","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-14T10:47:56.147441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256704803s","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-14T10:47:56.147514Z","caller":"traceutil/trace.go:171","msg":"trace[345923064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"1.256829526s","start":"2026-04-14T10:47:54.890665Z","end":"2026-04-14T10:47:56.147495Z","steps":["trace[345923064] 'range keys from in-memory index tree' (duration: 1.256373694s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:56.147558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:54.890654Z","time spent":"1.256893907s","remote":"127.0.0.1:39470","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-14T10:47:56.147564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252216664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T10:47:56.147584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252226283s","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-14T10:47:56.147619Z","caller":"traceutil/trace.go:171","msg":"trace[848616175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"1.252306706s","start":"2026-04-14T10:47:54.895304Z","end":"2026-04-14T10:47:56.14761Z","steps":["trace[848616175] 'range keys from in-memory index tree' (duration: 1.252122411s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:56.147632Z","caller":"traceutil/trace.go:171","msg":"trace[171688375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"1.252320416s","start":"2026-04-14T10:47:54.895286Z","end":"2026-04-14T10:47:56.147607Z","steps":["trace[171688375] 'range keys from in-memory index tree' (duration: 1.252058679s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:56.147653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:54.895269Z","time spent":"1.252377078s","remote":"127.0.0.1:39470","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-14T10:47:56.147679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:54.895275Z","time spent":"1.252396078s","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T10:47:56.404555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:56.905348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12931696602947678856,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-14T10:47:57.116384Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.215340368s","expected-duration":"1s"} {"level":"info","ts":"2026-04-14T10:47:57.116826Z","caller":"traceutil/trace.go:171","msg":"trace[207998073] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"2.215848871s","start":"2026-04-14T10:47:54.900919Z","end":"2026-04-14T10:47:57.116768Z","steps":["trace[207998073] 'process raft request' (duration: 2.215668027s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.116976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:54.900904Z","time spent":"2.215993545s","remote":"127.0.0.1:39388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:57.12967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000208982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-14T10:47:57.129764Z","caller":"traceutil/trace.go:171","msg":"trace[453572195] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000361956s","start":"2026-04-14T10:47:55.129385Z","end":"2026-04-14T10:47:57.129747Z","steps":["trace[453572195] 'agreement among raft nodes before linearized reading' (duration: 2.000222362s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.12982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:55.129364Z","time spent":"2.000435998s","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T10:47:57.158669Z","caller":"traceutil/trace.go:171","msg":"trace[1465805068] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"1.007938065s","start":"2026-04-14T10:47:56.150711Z","end":"2026-04-14T10:47:57.158649Z","steps":["trace[1465805068] 'process raft request' (duration: 1.007873683s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.158863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:56.150695Z","time spent":"1.008046348s","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:57.158855Z","caller":"traceutil/trace.go:171","msg":"trace[820444549] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.589003007s","start":"2026-04-14T10:47:55.569832Z","end":"2026-04-14T10:47:57.158835Z","steps":["trace[820444549] 'process raft request' (duration: 1.588675748s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.159734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:55.569818Z","time spent":"1.589819369s","remote":"127.0.0.1:39470","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-14T10:47:57.158891Z","caller":"traceutil/trace.go:171","msg":"trace[1394092651] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"1.599846086s","start":"2026-04-14T10:47:55.559024Z","end":"2026-04-14T10:47:57.15887Z","steps":["trace[1394092651] 'process raft request' (duration: 1.599019674s)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:57.158937Z","caller":"traceutil/trace.go:171","msg":"trace[808443162] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.008219773s","start":"2026-04-14T10:47:56.150689Z","end":"2026-04-14T10:47:57.158909Z","steps":["trace[808443162] 'process raft request' (duration: 1.007862674s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.159975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:55.559Z","time spent":"1.600902465s","remote":"127.0.0.1:39448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:57.159146Z","caller":"traceutil/trace.go:171","msg":"trace[1020388412] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"1.008097389s","start":"2026-04-14T10:47:56.151039Z","end":"2026-04-14T10:47:57.159136Z","steps":["trace[1020388412] 'process raft request' (duration: 1.007578775s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.160146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:56.151033Z","time spent":"1.009082696s","remote":"127.0.0.1:39470","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-14T10:47:57.159298Z","caller":"traceutil/trace.go:171","msg":"trace[504194757] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"1.007473633s","start":"2026-04-14T10:47:56.151748Z","end":"2026-04-14T10:47:57.159221Z","steps":["trace[504194757] 'process raft request' (duration: 1.007257027s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.160262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:56.150668Z","time spent":"1.009350683s","remote":"127.0.0.1:39470","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-14T10:47:57.160402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:56.151729Z","time spent":"1.00849101s","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:57.165914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.263000465s","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-14T10:47:57.165955Z","caller":"traceutil/trace.go:171","msg":"trace[341677359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"2.263067897s","start":"2026-04-14T10:47:54.902875Z","end":"2026-04-14T10:47:57.165943Z","steps":["trace[341677359] 'agreement among raft nodes before linearized reading' (duration: 2.262947814s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.165978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:54.902856Z","time spent":"2.263117068s","remote":"127.0.0.1:39470","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-14T10:47:57.165701Z","caller":"traceutil/trace.go:171","msg":"trace[1268731791] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:792; }","duration":"2.262760549s","start":"2026-04-14T10:47:54.902921Z","end":"2026-04-14T10:47:57.165681Z","steps":["trace[1268731791] 'read index received' (duration: 2.213884479s)","trace[1268731791] 'applied index is now lower than readState.Index' (duration: 48.87493ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:57.166603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.790895807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:47:57.166715Z","caller":"traceutil/trace.go:171","msg":"trace[39832346] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:765; }","duration":"1.791053132s","start":"2026-04-14T10:47:55.37565Z","end":"2026-04-14T10:47:57.166703Z","steps":["trace[39832346] 'agreement among raft nodes before linearized reading' (duration: 1.790811395s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.166839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:55.375631Z","time spent":"1.791191155s","remote":"127.0.0.1:39654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-14T10:47:57.167088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364946186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:47:57.167192Z","caller":"traceutil/trace.go:171","msg":"trace[550823010] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:765; }","duration":"1.365050799s","start":"2026-04-14T10:47:55.80213Z","end":"2026-04-14T10:47:57.167181Z","steps":["trace[550823010] 'agreement among raft nodes before linearized reading' (duration: 1.364929955s)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:57.167301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:55.802068Z","time spent":"1.365220103s","remote":"127.0.0.1:39502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-04-14T10:47:59.217504Z","caller":"traceutil/trace.go:171","msg":"trace[513494448] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"153.468151ms","start":"2026-04-14T10:47:59.063997Z","end":"2026-04-14T10:47:59.217465Z","steps":["trace[513494448] 'process raft request' (duration: 153.299757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:59.559663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.84514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:47:59.559756Z","caller":"traceutil/trace.go:171","msg":"trace[1477993371] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"337.287039ms","start":"2026-04-14T10:47:59.222452Z","end":"2026-04-14T10:47:59.559739Z","steps":["trace[1477993371] 'process raft request' (duration: 121.285355ms)","trace[1477993371] 'compare' (duration: 215.282775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:59.559819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:59.222433Z","time spent":"337.356911ms","remote":"127.0.0.1:39470","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-14T10:47:59.563189Z","caller":"traceutil/trace.go:171","msg":"trace[2075628814] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:806; }","duration":"130.636937ms","start":"2026-04-14T10:47:59.432538Z","end":"2026-04-14T10:47:59.563175Z","steps":["trace[2075628814] 'read index received' (duration: 130.292117ms)","trace[2075628814] 'applied index is now lower than readState.Index' (duration: 344.14µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:47:59.563481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.940284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ldb27\" ","response":"range_response_count:1 size:4226"} {"level":"info","ts":"2026-04-14T10:47:59.563544Z","caller":"traceutil/trace.go:171","msg":"trace[1627035586] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ldb27; range_end:; response_count:1; response_revision:779; }","duration":"131.041537ms","start":"2026-04-14T10:47:59.432483Z","end":"2026-04-14T10:47:59.563525Z","steps":["trace[1627035586] 'agreement among raft nodes before linearized reading' (duration: 130.885853ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:59.56357Z","caller":"traceutil/trace.go:171","msg":"trace[721981427] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"339.235641ms","start":"2026-04-14T10:47:59.224288Z","end":"2026-04-14T10:47:59.563524Z","steps":["trace[721981427] 'process raft request' (duration: 338.84089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:59.563754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:59.224277Z","time spent":"339.384625ms","remote":"127.0.0.1:39656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:59.564149Z","caller":"traceutil/trace.go:171","msg":"trace[2072592780] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"341.03637ms","start":"2026-04-14T10:47:59.2231Z","end":"2026-04-14T10:47:59.564136Z","steps":["trace[2072592780] 'process raft request' (duration: 339.916439ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:59.564152Z","caller":"traceutil/trace.go:171","msg":"trace[1531913426] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"341.339237ms","start":"2026-04-14T10:47:59.222792Z","end":"2026-04-14T10:47:59.564132Z","steps":["trace[1531913426] 'process raft request' (duration: 340.195517ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:59.563517Z","caller":"traceutil/trace.go:171","msg":"trace[764053581] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"340.008702ms","start":"2026-04-14T10:47:59.223476Z","end":"2026-04-14T10:47:59.563485Z","steps":["trace[764053581] 'process raft request' (duration: 339.611581ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:47:59.564309Z","caller":"traceutil/trace.go:171","msg":"trace[808070709] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"341.578254ms","start":"2026-04-14T10:47:59.222722Z","end":"2026-04-14T10:47:59.5643Z","steps":["trace[808070709] 'process raft request' (duration: 340.153066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:59.564395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:59.222715Z","time spent":"341.651376ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:59.564483Z","caller":"traceutil/trace.go:171","msg":"trace[1919110671] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"341.698998ms","start":"2026-04-14T10:47:59.222776Z","end":"2026-04-14T10:47:59.564475Z","steps":["trace[1919110671] 'process raft request' (duration: 340.177817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:59.564533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:59.222768Z","time spent":"341.739808ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:59.564256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:59.223093Z","time spent":"341.091351ms","remote":"127.0.0.1:39480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:59.564269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:59.222765Z","time spent":"341.4398ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:47:59.564868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:59.22346Z","time spent":"340.855574ms","remote":"127.0.0.1:39470","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-14T10:47:59.56719Z","caller":"traceutil/trace.go:171","msg":"trace[1168252476] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"340.212818ms","start":"2026-04-14T10:47:59.223145Z","end":"2026-04-14T10:47:59.563358Z","steps":["trace[1168252476] 'process raft request' (duration: 339.905719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:47:59.567369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:47:59.223127Z","time spent":"344.141013ms","remote":"127.0.0.1:39374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:47:59.675954Z","caller":"traceutil/trace.go:171","msg":"trace[32195111] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"102.457128ms","start":"2026-04-14T10:47:59.573472Z","end":"2026-04-14T10:47:59.675929Z","steps":["trace[32195111] 'process raft request' (duration: 102.294463ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:00.82847Z","caller":"traceutil/trace.go:171","msg":"trace[1536442348] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"186.630355ms","start":"2026-04-14T10:48:00.641816Z","end":"2026-04-14T10:48:00.828446Z","steps":["trace[1536442348] 'process raft request' (duration: 186.434919ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:01.731076Z","caller":"traceutil/trace.go:171","msg":"trace[1989006694] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"109.402337ms","start":"2026-04-14T10:48:01.62165Z","end":"2026-04-14T10:48:01.731052Z","steps":["trace[1989006694] 'process raft request' (duration: 107.624759ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:01.731382Z","caller":"traceutil/trace.go:171","msg":"trace[554395724] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"108.665077ms","start":"2026-04-14T10:48:01.62269Z","end":"2026-04-14T10:48:01.731355Z","steps":["trace[554395724] 'process raft request' (duration: 108.329598ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:01.731417Z","caller":"traceutil/trace.go:171","msg":"trace[581327510] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"107.088764ms","start":"2026-04-14T10:48:01.624312Z","end":"2026-04-14T10:48:01.731401Z","steps":["trace[581327510] 'process raft request' (duration: 107.036993ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:01.731515Z","caller":"traceutil/trace.go:171","msg":"trace[1350898048] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"107.387412ms","start":"2026-04-14T10:48:01.624064Z","end":"2026-04-14T10:48:01.731451Z","steps":["trace[1350898048] 'process raft request' (duration: 107.141616ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:04.766379Z","caller":"traceutil/trace.go:171","msg":"trace[434438164] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"162.979799ms","start":"2026-04-14T10:48:04.603378Z","end":"2026-04-14T10:48:04.766357Z","steps":["trace[434438164] 'process raft request' (duration: 162.836375ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:05.014916Z","caller":"traceutil/trace.go:171","msg":"trace[481268732] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"241.881984ms","start":"2026-04-14T10:48:04.772999Z","end":"2026-04-14T10:48:05.014881Z","steps":["trace[481268732] 'process raft request' (duration: 241.648488ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:05.058969Z","caller":"traceutil/trace.go:171","msg":"trace[1815532913] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"285.332237ms","start":"2026-04-14T10:48:04.773606Z","end":"2026-04-14T10:48:05.058939Z","steps":["trace[1815532913] 'process raft request' (duration: 285.111871ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:05.059127Z","caller":"traceutil/trace.go:171","msg":"trace[311987903] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"274.303067ms","start":"2026-04-14T10:48:04.78481Z","end":"2026-04-14T10:48:05.059113Z","steps":["trace[311987903] 'process raft request' (duration: 274.03838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:06.689163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.523872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:48:06.689432Z","caller":"traceutil/trace.go:171","msg":"trace[1901153413] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"412.703896ms","start":"2026-04-14T10:48:06.276703Z","end":"2026-04-14T10:48:06.689407Z","steps":["trace[1901153413] 'process raft request' (duration: 300.862426ms)","trace[1901153413] 'compare' (duration: 110.936976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:06.689501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:06.276683Z","time spent":"412.795948ms","remote":"127.0.0.1:39284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T10:48:08.087279Z","caller":"traceutil/trace.go:171","msg":"trace[1868983210] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"126.296475ms","start":"2026-04-14T10:48:07.96096Z","end":"2026-04-14T10:48:08.087257Z","steps":["trace[1868983210] 'process raft request' (duration: 117.242567ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.08741Z","caller":"traceutil/trace.go:171","msg":"trace[2017181968] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"120.285929ms","start":"2026-04-14T10:48:07.967105Z","end":"2026-04-14T10:48:08.087391Z","steps":["trace[2017181968] 'process raft request' (duration: 120.074864ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.411062Z","caller":"traceutil/trace.go:171","msg":"trace[368727378] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"153.038408ms","start":"2026-04-14T10:48:08.258009Z","end":"2026-04-14T10:48:08.411047Z","steps":["trace[368727378] 'process raft request' (duration: 152.994867ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.411384Z","caller":"traceutil/trace.go:171","msg":"trace[2000204521] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"156.089622ms","start":"2026-04-14T10:48:08.255285Z","end":"2026-04-14T10:48:08.411374Z","steps":["trace[2000204521] 'process raft request' (duration: 155.672201ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.411051Z","caller":"traceutil/trace.go:171","msg":"trace[1512072634] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"160.542303ms","start":"2026-04-14T10:48:08.250483Z","end":"2026-04-14T10:48:08.411026Z","steps":["trace[1512072634] 'process raft request' (duration: 160.017129ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.427381Z","caller":"traceutil/trace.go:171","msg":"trace[379947769] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:913; }","duration":"104.521931ms","start":"2026-04-14T10:48:08.32284Z","end":"2026-04-14T10:48:08.427362Z","steps":["trace[379947769] 'read index received' (duration: 87.810823ms)","trace[379947769] 'applied index is now lower than readState.Index' (duration: 16.710168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:08.42771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.85395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-p6jc9\" ","response":"range_response_count:1 size:4869"} {"level":"info","ts":"2026-04-14T10:48:08.427811Z","caller":"traceutil/trace.go:171","msg":"trace[1864937414] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-p6jc9; range_end:; response_count:1; response_revision:882; }","duration":"105.209899ms","start":"2026-04-14T10:48:08.322542Z","end":"2026-04-14T10:48:08.427752Z","steps":["trace[1864937414] 'agreement among raft nodes before linearized reading' (duration: 104.893931ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.428233Z","caller":"traceutil/trace.go:171","msg":"trace[1954206069] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"105.566169ms","start":"2026-04-14T10:48:08.322655Z","end":"2026-04-14T10:48:08.428221Z","steps":["trace[1954206069] 'process raft request' (duration: 104.571562ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:08.428556Z","caller":"traceutil/trace.go:171","msg":"trace[14671414] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"105.868087ms","start":"2026-04-14T10:48:08.322676Z","end":"2026-04-14T10:48:08.428544Z","steps":["trace[14671414] 'process raft request' (duration: 104.648254ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:09.237738Z","caller":"traceutil/trace.go:171","msg":"trace[1877803154] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"182.092312ms","start":"2026-04-14T10:48:09.055621Z","end":"2026-04-14T10:48:09.237713Z","steps":["trace[1877803154] 'read index received' (duration: 181.891377ms)","trace[1877803154] 'applied index is now lower than readState.Index' (duration: 200.165µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:48:09.237773Z","caller":"traceutil/trace.go:171","msg":"trace[1995951140] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"183.737227ms","start":"2026-04-14T10:48:09.054008Z","end":"2026-04-14T10:48:09.237745Z","steps":["trace[1995951140] 'process raft request' (duration: 183.567243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:09.237927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.280867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:48:09.237971Z","caller":"traceutil/trace.go:171","msg":"trace[999976959] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:0; response_revision:892; }","duration":"182.37332ms","start":"2026-04-14T10:48:09.055586Z","end":"2026-04-14T10:48:09.237959Z","steps":["trace[999976959] 'agreement among raft nodes before linearized reading' (duration: 182.263177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:09.238173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.793173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:48:09.238311Z","caller":"traceutil/trace.go:171","msg":"trace[1263214167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"109.000958ms","start":"2026-04-14T10:48:09.129275Z","end":"2026-04-14T10:48:09.238276Z","steps":["trace[1263214167] 'agreement among raft nodes before linearized reading' (duration: 108.662058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:12.133575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.856913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:48:12.1337Z","caller":"traceutil/trace.go:171","msg":"trace[1269581035] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"210.913737ms","start":"2026-04-14T10:48:11.922764Z","end":"2026-04-14T10:48:12.133678Z","steps":["trace[1269581035] 'process raft request' (duration: 78.868689ms)","trace[1269581035] 'compare' (duration: 131.594716ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:48:12.206392Z","caller":"traceutil/trace.go:171","msg":"trace[290185682] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"283.255649ms","start":"2026-04-14T10:48:11.923111Z","end":"2026-04-14T10:48:12.206366Z","steps":["trace[290185682] 'process raft request' (duration: 283.003002ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:12.207054Z","caller":"traceutil/trace.go:171","msg":"trace[1769376366] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"283.876616ms","start":"2026-04-14T10:48:11.923144Z","end":"2026-04-14T10:48:12.207021Z","steps":["trace[1769376366] 'process raft request' (duration: 283.173717ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:12.20711Z","caller":"traceutil/trace.go:171","msg":"trace[431670182] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:965; }","duration":"151.37264ms","start":"2026-04-14T10:48:12.05571Z","end":"2026-04-14T10:48:12.207082Z","steps":["trace[431670182] 'read index received' (duration: 150.449965ms)","trace[431670182] 'applied index is now lower than readState.Index' (duration: 921.405µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:48:12.207367Z","caller":"traceutil/trace.go:171","msg":"trace[1955169879] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"284.163375ms","start":"2026-04-14T10:48:11.92319Z","end":"2026-04-14T10:48:12.207353Z","steps":["trace[1955169879] 'process raft request' (duration: 283.768474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:12.207545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.792689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T10:48:12.207616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.902034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-kqtmh\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-04-14T10:48:12.207672Z","caller":"traceutil/trace.go:171","msg":"trace[1021581528] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:931; }","duration":"140.997534ms","start":"2026-04-14T10:48:12.066639Z","end":"2026-04-14T10:48:12.207636Z","steps":["trace[1021581528] 'agreement among raft nodes before linearized reading' (duration: 140.772058ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:12.207678Z","caller":"traceutil/trace.go:171","msg":"trace[864133176] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-kqtmh; range_end:; response_count:1; response_revision:931; }","duration":"151.999137ms","start":"2026-04-14T10:48:12.055659Z","end":"2026-04-14T10:48:12.207658Z","steps":["trace[864133176] 'agreement among raft nodes before linearized reading' (duration: 151.74331ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:12.525965Z","caller":"traceutil/trace.go:171","msg":"trace[678899434] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"233.784101ms","start":"2026-04-14T10:48:12.292143Z","end":"2026-04-14T10:48:12.525927Z","steps":["trace[678899434] 'process raft request' (duration: 233.565315ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:12.746435Z","caller":"traceutil/trace.go:171","msg":"trace[1064367579] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"452.110975ms","start":"2026-04-14T10:48:12.294287Z","end":"2026-04-14T10:48:12.746398Z","steps":["trace[1064367579] 'process raft request' (duration: 452.011373ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:12.7465Z","caller":"traceutil/trace.go:171","msg":"trace[1074550653] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"452.991819ms","start":"2026-04-14T10:48:12.293478Z","end":"2026-04-14T10:48:12.74647Z","steps":["trace[1074550653] 'process raft request' (duration: 437.354448ms)","trace[1074550653] 'compare' (duration: 15.255401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:12.746672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:12.293463Z","time spent":"453.116692ms","remote":"127.0.0.1:39480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1628,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:48:12.746686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:48:12.294276Z","time spent":"452.273789ms","remote":"127.0.0.1:39656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T10:48:14.06287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.527057ms","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-14T10:48:14.062939Z","caller":"traceutil/trace.go:171","msg":"trace[990066436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:938; }","duration":"141.65119ms","start":"2026-04-14T10:48:13.921272Z","end":"2026-04-14T10:48:14.062924Z","steps":["trace[990066436] 'range keys from in-memory index tree' (duration: 141.385313ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:14.29303Z","caller":"traceutil/trace.go:171","msg":"trace[431430085] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"151.443703ms","start":"2026-04-14T10:48:14.141568Z","end":"2026-04-14T10:48:14.293012Z","steps":["trace[431430085] 'process raft request' (duration: 151.047692ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:16.21573Z","caller":"traceutil/trace.go:171","msg":"trace[1620392813] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"114.02109ms","start":"2026-04-14T10:48:16.101679Z","end":"2026-04-14T10:48:16.2157Z","steps":["trace[1620392813] 'process raft request' (duration: 113.857315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:48:16.44448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.763781ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T10:48:16.444596Z","caller":"traceutil/trace.go:171","msg":"trace[773523156] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"110.469562ms","start":"2026-04-14T10:48:16.334109Z","end":"2026-04-14T10:48:16.444579Z","steps":["trace[773523156] 'read index received' (duration: 8.542497ms)","trace[773523156] 'applied index is now lower than readState.Index' (duration: 101.925795ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:48:16.444802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.685728ms","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-14T10:48:16.44484Z","caller":"traceutil/trace.go:171","msg":"trace[833944585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"110.771671ms","start":"2026-04-14T10:48:16.334057Z","end":"2026-04-14T10:48:16.444828Z","steps":["trace[833944585] 'agreement among raft nodes before linearized reading' (duration: 110.619636ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:28.379542Z","caller":"traceutil/trace.go:171","msg":"trace[439933891] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"114.152825ms","start":"2026-04-14T10:48:28.265354Z","end":"2026-04-14T10:48:28.379507Z","steps":["trace[439933891] 'process raft request' (duration: 113.937209ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:45.686204Z","caller":"traceutil/trace.go:171","msg":"trace[1318460423] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"191.452139ms","start":"2026-04-14T10:48:45.494735Z","end":"2026-04-14T10:48:45.686187Z","steps":["trace[1318460423] 'process raft request' (duration: 191.349956ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:48:59.964864Z","caller":"traceutil/trace.go:171","msg":"trace[332042505] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"215.077297ms","start":"2026-04-14T10:48:59.749762Z","end":"2026-04-14T10:48:59.96484Z","steps":["trace[332042505] 'process raft request' (duration: 214.926932ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:02.155062Z","caller":"traceutil/trace.go:171","msg":"trace[968558740] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"182.803611ms","start":"2026-04-14T10:49:01.972233Z","end":"2026-04-14T10:49:02.155036Z","steps":["trace[968558740] 'process raft request' (duration: 182.546323ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:04.61497Z","caller":"traceutil/trace.go:171","msg":"trace[1509964966] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"111.572766ms","start":"2026-04-14T10:49:04.503365Z","end":"2026-04-14T10:49:04.614938Z","steps":["trace[1509964966] 'process raft request' (duration: 111.383631ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:12.355389Z","caller":"traceutil/trace.go:171","msg":"trace[293975379] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1194; }","duration":"126.18594ms","start":"2026-04-14T10:49:12.229167Z","end":"2026-04-14T10:49:12.355353Z","steps":["trace[293975379] 'read index received' (duration: 126.025045ms)","trace[293975379] 'applied index is now lower than readState.Index' (duration: 159.275µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:12.355418Z","caller":"traceutil/trace.go:171","msg":"trace[770988251] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"157.866847ms","start":"2026-04-14T10:49:12.197516Z","end":"2026-04-14T10:49:12.355383Z","steps":["trace[770988251] 'process raft request' (duration: 157.597609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:12.35565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.461408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-04-14T10:49:12.355689Z","caller":"traceutil/trace.go:171","msg":"trace[551414919] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1145; }","duration":"126.555231ms","start":"2026-04-14T10:49:12.22912Z","end":"2026-04-14T10:49:12.355676Z","steps":["trace[551414919] 'agreement among raft nodes before linearized reading' (duration: 126.360395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:16.993157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.301989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:49:16.993285Z","caller":"traceutil/trace.go:171","msg":"trace[380883756] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"215.64311ms","start":"2026-04-14T10:49:16.777616Z","end":"2026-04-14T10:49:16.993259Z","steps":["trace[380883756] 'process raft request' (duration: 85.137226ms)","trace[380883756] 'compare' (duration: 130.003409ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:17.333847Z","caller":"traceutil/trace.go:171","msg":"trace[409538733] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1212; }","duration":"261.389185ms","start":"2026-04-14T10:49:17.072415Z","end":"2026-04-14T10:49:17.333804Z","steps":["trace[409538733] 'read index received' (duration: 226.07527ms)","trace[409538733] 'applied index is now lower than readState.Index' (duration: 35.312385ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:17.333996Z","caller":"traceutil/trace.go:171","msg":"trace[1866018910] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"314.809101ms","start":"2026-04-14T10:49:17.01917Z","end":"2026-04-14T10:49:17.333979Z","steps":["trace[1866018910] 'process raft request' (duration: 279.405383ms)","trace[1866018910] 'compare' (duration: 34.940055ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:17.334118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:17.01916Z","time spent":"314.864623ms","remote":"127.0.0.1:39470","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-14T10:49:17.334145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.98441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:17.334218Z","caller":"traceutil/trace.go:171","msg":"trace[1569657943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1162; }","duration":"205.110715ms","start":"2026-04-14T10:49:17.12909Z","end":"2026-04-14T10:49:17.334201Z","steps":["trace[1569657943] 'agreement among raft nodes before linearized reading' (duration: 204.985611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:17.334222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.800987ms","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-14T10:49:17.334268Z","caller":"traceutil/trace.go:171","msg":"trace[1532321409] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1162; }","duration":"261.894729ms","start":"2026-04-14T10:49:17.072361Z","end":"2026-04-14T10:49:17.334256Z","steps":["trace[1532321409] 'agreement among raft nodes before linearized reading' (duration: 261.707244ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:19.316788Z","caller":"traceutil/trace.go:171","msg":"trace[1459221270] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"187.825114ms","start":"2026-04-14T10:49:19.128927Z","end":"2026-04-14T10:49:19.316752Z","steps":["trace[1459221270] 'read index received' (duration: 107.62841ms)","trace[1459221270] 'applied index is now lower than readState.Index' (duration: 80.195064ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:19.3169Z","caller":"traceutil/trace.go:171","msg":"trace[790226437] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"254.378967ms","start":"2026-04-14T10:49:19.062417Z","end":"2026-04-14T10:49:19.316796Z","steps":["trace[790226437] 'process raft request' (duration: 174.173793ms)","trace[790226437] 'compare' (duration: 79.932956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:19.316926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.978948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:19.316996Z","caller":"traceutil/trace.go:171","msg":"trace[1291264895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"188.111122ms","start":"2026-04-14T10:49:19.128864Z","end":"2026-04-14T10:49:19.316975Z","steps":["trace[1291264895] 'agreement among raft nodes before linearized reading' (duration: 187.985789ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:19.479871Z","caller":"traceutil/trace.go:171","msg":"trace[2041663008] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"106.450215ms","start":"2026-04-14T10:49:19.373392Z","end":"2026-04-14T10:49:19.479842Z","steps":["trace[2041663008] 'process raft request' (duration: 89.429105ms)","trace[2041663008] 'compare' (duration: 16.902957ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:40.229522Z","caller":"traceutil/trace.go:171","msg":"trace[528243241] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"242.486953ms","start":"2026-04-14T10:49:39.987013Z","end":"2026-04-14T10:49:40.2295Z","steps":["trace[528243241] 'process raft request' (duration: 242.305297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:40.22974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.320834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ce3d7e79-363d-4c79-a607-9e6265d29cc5\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-14T10:49:40.229787Z","caller":"traceutil/trace.go:171","msg":"trace[96685300] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ce3d7e79-363d-4c79-a607-9e6265d29cc5; range_end:; response_count:1; response_revision:1252; }","duration":"122.444578ms","start":"2026-04-14T10:49:40.10733Z","end":"2026-04-14T10:49:40.229774Z","steps":["trace[96685300] 'agreement among raft nodes before linearized reading' (duration: 122.237242ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:40.22951Z","caller":"traceutil/trace.go:171","msg":"trace[574857590] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1307; }","duration":"122.074196ms","start":"2026-04-14T10:49:40.107409Z","end":"2026-04-14T10:49:40.229483Z","steps":["trace[574857590] 'read index received' (duration: 121.88621ms)","trace[574857590] 'applied index is now lower than readState.Index' (duration: 186.906µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:40.230019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.58742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:40.230056Z","caller":"traceutil/trace.go:171","msg":"trace[219803302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"100.667172ms","start":"2026-04-14T10:49:40.129377Z","end":"2026-04-14T10:49:40.230045Z","steps":["trace[219803302] 'agreement among raft nodes before linearized reading' (duration: 100.61098ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:40.698016Z","caller":"traceutil/trace.go:171","msg":"trace[228134965] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"460.434371ms","start":"2026-04-14T10:49:40.237561Z","end":"2026-04-14T10:49:40.697995Z","steps":["trace[228134965] 'process raft request' (duration: 460.314408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:40.698178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:40.237533Z","time spent":"460.550024ms","remote":"127.0.0.1:39400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:40.698298Z","caller":"traceutil/trace.go:171","msg":"trace[75601253] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1309; }","duration":"349.226356ms","start":"2026-04-14T10:49:40.349035Z","end":"2026-04-14T10:49:40.698261Z","steps":["trace[75601253] 'read index received' (duration: 349.216116ms)","trace[75601253] 'applied index is now lower than readState.Index' (duration: 8.39µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:40.698346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.322458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T10:49:40.698572Z","caller":"traceutil/trace.go:171","msg":"trace[2005193099] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1253; }","duration":"349.596397ms","start":"2026-04-14T10:49:40.348965Z","end":"2026-04-14T10:49:40.698562Z","steps":["trace[2005193099] 'agreement among raft nodes before linearized reading' (duration: 349.342649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:40.6986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:40.348939Z","time spent":"349.650879ms","remote":"127.0.0.1:39602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-14T10:49:43.826886Z","caller":"traceutil/trace.go:171","msg":"trace[1084530984] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"115.781634ms","start":"2026-04-14T10:49:43.711074Z","end":"2026-04-14T10:49:43.826856Z","steps":["trace[1084530984] 'process raft request' (duration: 115.243748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:44.896402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.873817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:49:44.896579Z","caller":"traceutil/trace.go:171","msg":"trace[2146546829] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"241.292878ms","start":"2026-04-14T10:49:44.655254Z","end":"2026-04-14T10:49:44.896547Z","steps":["trace[2146546829] 'process raft request' (duration: 122.158014ms)","trace[2146546829] 'compare' (duration: 118.476025ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:44.896672Z","caller":"traceutil/trace.go:171","msg":"trace[1268988767] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"110.179588ms","start":"2026-04-14T10:49:44.786472Z","end":"2026-04-14T10:49:44.896652Z","steps":["trace[1268988767] 'process raft request' (duration: 110.067325ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:44.896899Z","caller":"traceutil/trace.go:171","msg":"trace[30760389] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"240.841866ms","start":"2026-04-14T10:49:44.656018Z","end":"2026-04-14T10:49:44.896859Z","steps":["trace[30760389] 'read index received' (duration: 121.386731ms)","trace[30760389] 'applied index is now lower than readState.Index' (duration: 119.450984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:44.897282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.584443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-04-14T10:49:44.897427Z","caller":"traceutil/trace.go:171","msg":"trace[1425732190] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1280; }","duration":"165.783869ms","start":"2026-04-14T10:49:44.731625Z","end":"2026-04-14T10:49:44.897409Z","steps":["trace[1425732190] 'agreement among raft nodes before linearized reading' (duration: 165.47215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:44.897269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.213076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-04-14T10:49:44.897548Z","caller":"traceutil/trace.go:171","msg":"trace[598837668] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1280; }","duration":"241.546846ms","start":"2026-04-14T10:49:44.655983Z","end":"2026-04-14T10:49:44.89753Z","steps":["trace[598837668] 'agreement among raft nodes before linearized reading' (duration: 241.00588ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:45.798962Z","caller":"traceutil/trace.go:171","msg":"trace[2098675448] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"112.53354ms","start":"2026-04-14T10:49:45.686394Z","end":"2026-04-14T10:49:45.798928Z","steps":["trace[2098675448] 'process raft request' (duration: 112.314474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:47.321102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.863251ms","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-14T10:49:47.321189Z","caller":"traceutil/trace.go:171","msg":"trace[416058109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"212.014526ms","start":"2026-04-14T10:49:47.109154Z","end":"2026-04-14T10:49:47.321169Z","steps":["trace[416058109] 'range keys from in-memory index tree' (duration: 211.553382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:47.321102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.927729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:47.321567Z","caller":"traceutil/trace.go:171","msg":"trace[1799346634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"192.416553ms","start":"2026-04-14T10:49:47.129103Z","end":"2026-04-14T10:49:47.321519Z","steps":["trace[1799346634] 'range keys from in-memory index tree' (duration: 191.857357ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:47.447771Z","caller":"traceutil/trace.go:171","msg":"trace[1871851303] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"122.168539ms","start":"2026-04-14T10:49:47.325572Z","end":"2026-04-14T10:49:47.44774Z","steps":["trace[1871851303] 'process raft request' (duration: 121.971533ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:47.483698Z","caller":"traceutil/trace.go:171","msg":"trace[925572009] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"157.254001ms","start":"2026-04-14T10:49:47.326373Z","end":"2026-04-14T10:49:47.483627Z","steps":["trace[925572009] 'process raft request' (duration: 157.070585ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:48.124658Z","caller":"traceutil/trace.go:171","msg":"trace[655903219] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"161.26403ms","start":"2026-04-14T10:49:47.963359Z","end":"2026-04-14T10:49:48.124623Z","steps":["trace[655903219] 'read index received' (duration: 158.832628ms)","trace[655903219] 'applied index is now lower than readState.Index' (duration: 2.429212ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:48.1247Z","caller":"traceutil/trace.go:171","msg":"trace[2085150104] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"184.800559ms","start":"2026-04-14T10:49:47.939877Z","end":"2026-04-14T10:49:48.124678Z","steps":["trace[2085150104] 'process raft request' (duration: 182.235363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:48.12536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.981922ms","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-14T10:49:48.125451Z","caller":"traceutil/trace.go:171","msg":"trace[1450681016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"162.131706ms","start":"2026-04-14T10:49:47.963297Z","end":"2026-04-14T10:49:48.125429Z","steps":["trace[1450681016] 'agreement among raft nodes before linearized reading' (duration: 161.507848ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:49.308297Z","caller":"traceutil/trace.go:171","msg":"trace[1201823766] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"180.548545ms","start":"2026-04-14T10:49:49.127719Z","end":"2026-04-14T10:49:49.308268Z","steps":["trace[1201823766] 'read index received' (duration: 180.286688ms)","trace[1201823766] 'applied index is now lower than readState.Index' (duration: 260.677µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:49.308523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.772462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:49.308558Z","caller":"traceutil/trace.go:171","msg":"trace[1356216975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"180.882035ms","start":"2026-04-14T10:49:49.127665Z","end":"2026-04-14T10:49:49.308547Z","steps":["trace[1356216975] 'agreement among raft nodes before linearized reading' (duration: 180.793762ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:49.308524Z","caller":"traceutil/trace.go:171","msg":"trace[1662327489] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"230.387377ms","start":"2026-04-14T10:49:49.078097Z","end":"2026-04-14T10:49:49.308485Z","steps":["trace[1662327489] 'process raft request' (duration: 229.984665ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:50.200227Z","caller":"traceutil/trace.go:171","msg":"trace[1786766419] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"352.848587ms","start":"2026-04-14T10:49:49.847346Z","end":"2026-04-14T10:49:50.200194Z","steps":["trace[1786766419] 'process raft request' (duration: 352.60804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:50.200461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:49.847322Z","time spent":"352.999772ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:51.346327Z","caller":"traceutil/trace.go:171","msg":"trace[2147229519] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"241.220182ms","start":"2026-04-14T10:49:51.10508Z","end":"2026-04-14T10:49:51.3463Z","steps":["trace[2147229519] 'read index received' (duration: 241.011086ms)","trace[2147229519] 'applied index is now lower than readState.Index' (duration: 207.826µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:51.346378Z","caller":"traceutil/trace.go:171","msg":"trace[2063722485] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"268.170005ms","start":"2026-04-14T10:49:51.078181Z","end":"2026-04-14T10:49:51.346351Z","steps":["trace[2063722485] 'process raft request' (duration: 267.970919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:51.346495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.393168ms","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-14T10:49:51.346568Z","caller":"traceutil/trace.go:171","msg":"trace[1113729343] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1304; }","duration":"241.517282ms","start":"2026-04-14T10:49:51.10501Z","end":"2026-04-14T10:49:51.346527Z","steps":["trace[1113729343] 'agreement among raft nodes before linearized reading' (duration: 241.420969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:51.34662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.21432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:51.346723Z","caller":"traceutil/trace.go:171","msg":"trace[329898212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"217.362303ms","start":"2026-04-14T10:49:51.129333Z","end":"2026-04-14T10:49:51.346696Z","steps":["trace[329898212] 'agreement among raft nodes before linearized reading' (duration: 217.169387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:51.707686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.608573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:49:51.707877Z","caller":"traceutil/trace.go:171","msg":"trace[2098739937] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"242.12704ms","start":"2026-04-14T10:49:51.465731Z","end":"2026-04-14T10:49:51.707858Z","steps":["trace[2098739937] 'read index received' (duration: 113.209327ms)","trace[2098739937] 'applied index is now lower than readState.Index' (duration: 128.916363ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:51.707975Z","caller":"traceutil/trace.go:171","msg":"trace[1801632505] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"244.278714ms","start":"2026-04-14T10:49:51.463685Z","end":"2026-04-14T10:49:51.707963Z","steps":["trace[1801632505] 'process raft request' (duration: 115.295369ms)","trace[1801632505] 'compare' (duration: 128.48529ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:51.708845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.248811ms","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-14T10:49:51.709015Z","caller":"traceutil/trace.go:171","msg":"trace[1643815930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1305; }","duration":"212.433367ms","start":"2026-04-14T10:49:51.496539Z","end":"2026-04-14T10:49:51.708973Z","steps":["trace[1643815930] 'agreement among raft nodes before linearized reading' (duration: 211.469638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:51.709182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.4476ms","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-14T10:49:51.709255Z","caller":"traceutil/trace.go:171","msg":"trace[57793168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1305; }","duration":"243.541282ms","start":"2026-04-14T10:49:51.465685Z","end":"2026-04-14T10:49:51.709226Z","steps":["trace[57793168] 'agreement among raft nodes before linearized reading' (duration: 242.346157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:52.018218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.902989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T10:49:52.018466Z","caller":"traceutil/trace.go:171","msg":"trace[1354826524] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"301.420683ms","start":"2026-04-14T10:49:51.717015Z","end":"2026-04-14T10:49:52.018435Z","steps":["trace[1354826524] 'process raft request' (duration: 118.197965ms)","trace[1354826524] 'compare' (duration: 182.715373ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:49:52.018577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:51.717001Z","time spent":"301.528937ms","remote":"127.0.0.1:39470","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-14T10:49:52.421364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.683476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:52.421461Z","caller":"traceutil/trace.go:171","msg":"trace[963996787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"291.851022ms","start":"2026-04-14T10:49:52.129588Z","end":"2026-04-14T10:49:52.421439Z","steps":["trace[963996787] 'range keys from in-memory index tree' (duration: 291.593954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:52.421681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.853933ms","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-14T10:49:52.421731Z","caller":"traceutil/trace.go:171","msg":"trace[524389378] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"166.957626ms","start":"2026-04-14T10:49:52.254761Z","end":"2026-04-14T10:49:52.421719Z","steps":["trace[524389378] 'range keys from in-memory index tree' (duration: 166.673678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:52.421829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.18732ms","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-14T10:49:52.421933Z","caller":"traceutil/trace.go:171","msg":"trace[529451103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"217.339925ms","start":"2026-04-14T10:49:52.204555Z","end":"2026-04-14T10:49:52.421895Z","steps":["trace[529451103] 'range keys from in-memory index tree' (duration: 216.900142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:52.421948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.016098ms","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-14T10:49:52.422004Z","caller":"traceutil/trace.go:171","msg":"trace[1382570629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"166.094831ms","start":"2026-04-14T10:49:52.255894Z","end":"2026-04-14T10:49:52.421989Z","steps":["trace[1382570629] 'range keys from in-memory index tree' (duration: 165.820753ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:52.724869Z","caller":"traceutil/trace.go:171","msg":"trace[914530409] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"298.541581ms","start":"2026-04-14T10:49:52.426305Z","end":"2026-04-14T10:49:52.724847Z","steps":["trace[914530409] 'process raft request' (duration: 261.914411ms)","trace[914530409] 'compare' (duration: 36.205967ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:52.725133Z","caller":"traceutil/trace.go:171","msg":"trace[762280322] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1367; }","duration":"246.781681ms","start":"2026-04-14T10:49:52.478308Z","end":"2026-04-14T10:49:52.725089Z","steps":["trace[762280322] 'read index received' (duration: 209.914294ms)","trace[762280322] 'applied index is now lower than readState.Index' (duration: 36.865617ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:52.725302Z","caller":"traceutil/trace.go:171","msg":"trace[784449866] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"298.748206ms","start":"2026-04-14T10:49:52.426545Z","end":"2026-04-14T10:49:52.725293Z","steps":["trace[784449866] 'process raft request' (duration: 298.237251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:52.725317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.971776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:49:52.725418Z","caller":"traceutil/trace.go:171","msg":"trace[1073893147] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1310; }","duration":"247.137331ms","start":"2026-04-14T10:49:52.478265Z","end":"2026-04-14T10:49:52.725403Z","steps":["trace[1073893147] 'agreement among raft nodes before linearized reading' (duration: 246.950955ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:49:58.679932Z","caller":"traceutil/trace.go:171","msg":"trace[321241410] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"539.600032ms","start":"2026-04-14T10:49:58.140315Z","end":"2026-04-14T10:49:58.679915Z","steps":["trace[321241410] 'process raft request' (duration: 539.483511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.680044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:49:58.1403Z","time spent":"539.685663ms","remote":"127.0.0.1:39470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T10:49:58.727442Z","caller":"traceutil/trace.go:171","msg":"trace[2025541898] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1391; }","duration":"128.852065ms","start":"2026-04-14T10:49:58.598565Z","end":"2026-04-14T10:49:58.727417Z","steps":["trace[2025541898] 'read index received' (duration: 81.276684ms)","trace[2025541898] 'applied index is now lower than readState.Index' (duration: 47.574291ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:49:58.727506Z","caller":"traceutil/trace.go:171","msg":"trace[1890688858] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"153.933636ms","start":"2026-04-14T10:49:58.573533Z","end":"2026-04-14T10:49:58.727467Z","steps":["trace[1890688858] 'process raft request' (duration: 153.721143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:49:58.72767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.083618ms","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-14T10:49:58.727735Z","caller":"traceutil/trace.go:171","msg":"trace[101123951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1333; }","duration":"129.19316ms","start":"2026-04-14T10:49:58.598526Z","end":"2026-04-14T10:49:58.727719Z","steps":["trace[101123951] 'agreement among raft nodes before linearized reading' (duration: 128.984827ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:00.865427Z","caller":"traceutil/trace.go:171","msg":"trace[657636770] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1405; }","duration":"107.276534ms","start":"2026-04-14T10:50:00.758123Z","end":"2026-04-14T10:50:00.8654Z","steps":["trace[657636770] 'read index received' (duration: 46.923285ms)","trace[657636770] 'applied index is now lower than readState.Index' (duration: 60.351659ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:00.865512Z","caller":"traceutil/trace.go:171","msg":"trace[1003538660] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"110.407782ms","start":"2026-04-14T10:50:00.755044Z","end":"2026-04-14T10:50:00.865452Z","steps":["trace[1003538660] 'process raft request' (duration: 50.076633ms)","trace[1003538660] 'compare' (duration: 60.129927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:50:00.865778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.585627ms","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-14T10:50:00.865873Z","caller":"traceutil/trace.go:171","msg":"trace[1856809214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1345; }","duration":"107.751179ms","start":"2026-04-14T10:50:00.7581Z","end":"2026-04-14T10:50:00.865851Z","steps":["trace[1856809214] 'agreement among raft nodes before linearized reading' (duration: 107.395935ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:01.003732Z","caller":"traceutil/trace.go:171","msg":"trace[1150853280] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"132.055428ms","start":"2026-04-14T10:50:00.871652Z","end":"2026-04-14T10:50:01.003707Z","steps":["trace[1150853280] 'process raft request' (duration: 131.892906ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:10.165444Z","caller":"traceutil/trace.go:171","msg":"trace[31659291] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"130.548277ms","start":"2026-04-14T10:50:10.034871Z","end":"2026-04-14T10:50:10.16542Z","steps":["trace[31659291] 'read index received' (duration: 130.352534ms)","trace[31659291] 'applied index is now lower than readState.Index' (duration: 194.713µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:10.165486Z","caller":"traceutil/trace.go:171","msg":"trace[93552178] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"133.623537ms","start":"2026-04-14T10:50:10.031831Z","end":"2026-04-14T10:50:10.165454Z","steps":["trace[93552178] 'process raft request' (duration: 133.407004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:10.165635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.743829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-04-14T10:50:10.165713Z","caller":"traceutil/trace.go:171","msg":"trace[675809228] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1376; }","duration":"130.85703ms","start":"2026-04-14T10:50:10.034831Z","end":"2026-04-14T10:50:10.165688Z","steps":["trace[675809228] 'agreement among raft nodes before linearized reading' (duration: 130.711158ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:11.443694Z","caller":"traceutil/trace.go:171","msg":"trace[2026039781] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"100.778167ms","start":"2026-04-14T10:50:11.342881Z","end":"2026-04-14T10:50:11.443659Z","steps":["trace[2026039781] 'process raft request' (duration: 49.898877ms)","trace[2026039781] 'compare' (duration: 50.633887ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:11.583415Z","caller":"traceutil/trace.go:171","msg":"trace[977996459] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"110.499949ms","start":"2026-04-14T10:50:11.47289Z","end":"2026-04-14T10:50:11.58339Z","steps":["trace[977996459] 'process raft request' (duration: 98.79186ms)","trace[977996459] 'compare' (duration: 11.546927ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:13.719483Z","caller":"traceutil/trace.go:171","msg":"trace[2137204753] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"130.44883ms","start":"2026-04-14T10:50:13.589009Z","end":"2026-04-14T10:50:13.719458Z","steps":["trace[2137204753] 'process raft request' (duration: 130.220706ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:17.939844Z","caller":"traceutil/trace.go:171","msg":"trace[540155902] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"144.352326ms","start":"2026-04-14T10:50:17.795472Z","end":"2026-04-14T10:50:17.939825Z","steps":["trace[540155902] 'read index received' (duration: 144.149353ms)","trace[540155902] 'applied index is now lower than readState.Index' (duration: 202.103µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T10:50:17.93988Z","caller":"traceutil/trace.go:171","msg":"trace[1972267723] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"158.562129ms","start":"2026-04-14T10:50:17.781299Z","end":"2026-04-14T10:50:17.939861Z","steps":["trace[1972267723] 'process raft request' (duration: 158.398166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:17.9401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.05521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/envoy-gateway-system/\" range_end:\"/registry/persistentvolumeclaims/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:50:17.940156Z","caller":"traceutil/trace.go:171","msg":"trace[261387849] range","detail":"{range_begin:/registry/persistentvolumeclaims/envoy-gateway-system/; range_end:/registry/persistentvolumeclaims/envoy-gateway-system0; response_count:0; response_revision:1401; }","duration":"101.176261ms","start":"2026-04-14T10:50:17.838965Z","end":"2026-04-14T10:50:17.940142Z","steps":["trace[261387849] 'agreement among raft nodes before linearized reading' (duration: 101.080479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:17.940401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.684071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-kqtmh\" ","response":"range_response_count:1 size:5487"} {"level":"info","ts":"2026-04-14T10:50:17.94106Z","caller":"traceutil/trace.go:171","msg":"trace[133362402] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-kqtmh; range_end:; response_count:1; response_revision:1401; }","duration":"145.001695ms","start":"2026-04-14T10:50:17.795445Z","end":"2026-04-14T10:50:17.940446Z","steps":["trace[133362402] 'agreement among raft nodes before linearized reading' (duration: 144.490088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:18.337658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.372841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:50:18.337751Z","caller":"traceutil/trace.go:171","msg":"trace[303154436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1401; }","duration":"208.513503ms","start":"2026-04-14T10:50:18.129214Z","end":"2026-04-14T10:50:18.337728Z","steps":["trace[303154436] 'range keys from in-memory index tree' (duration: 208.27691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:18.337858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.845471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-04-14T10:50:18.337941Z","caller":"traceutil/trace.go:171","msg":"trace[1975240326] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1401; }","duration":"257.949032ms","start":"2026-04-14T10:50:18.079969Z","end":"2026-04-14T10:50:18.337918Z","steps":["trace[1975240326] 'range keys from in-memory index tree' (duration: 257.616087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:18.338052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.045957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T10:50:18.338184Z","caller":"traceutil/trace.go:171","msg":"trace[31274593] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1401; }","duration":"229.254871ms","start":"2026-04-14T10:50:18.108901Z","end":"2026-04-14T10:50:18.338156Z","steps":["trace[31274593] 'count revisions from in-memory index tree' (duration: 228.899135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:18.33857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.533855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3302"} {"level":"info","ts":"2026-04-14T10:50:18.338707Z","caller":"traceutil/trace.go:171","msg":"trace[669246009] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/; range_end:/registry/rolebindings/envoy-gateway-system0; response_count:3; response_revision:1401; }","duration":"321.729897ms","start":"2026-04-14T10:50:18.016953Z","end":"2026-04-14T10:50:18.338683Z","steps":["trace[669246009] 'range keys from in-memory index tree' (duration: 321.315052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:18.338758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T10:50:18.016936Z","time spent":"321.807999ms","remote":"127.0.0.1:39524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":3,"response size":3325,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 "} {"level":"warn","ts":"2026-04-14T10:50:26.704143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.358421ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-14T10:50:26.704295Z","caller":"traceutil/trace.go:171","msg":"trace[741582171] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1495; }","duration":"224.702195ms","start":"2026-04-14T10:50:26.479569Z","end":"2026-04-14T10:50:26.704271Z","steps":["trace[741582171] 'read index received' (duration: 65.14869ms)","trace[741582171] 'applied index is now lower than readState.Index' (duration: 159.552025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T10:50:26.704435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.854947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-14T10:50:26.704462Z","caller":"traceutil/trace.go:171","msg":"trace[464317079] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1429; }","duration":"224.927718ms","start":"2026-04-14T10:50:26.479528Z","end":"2026-04-14T10:50:26.704455Z","steps":["trace[464317079] 'agreement among raft nodes before linearized reading' (duration: 224.845377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T10:50:26.705129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.405979ms","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:72 size:53351"} {"level":"info","ts":"2026-04-14T10:50:26.705198Z","caller":"traceutil/trace.go:171","msg":"trace[836399643] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:72; response_revision:1429; }","duration":"222.495551ms","start":"2026-04-14T10:50:26.482686Z","end":"2026-04-14T10:50:26.705181Z","steps":["trace[836399643] 'agreement among raft nodes before linearized reading' (duration: 221.75764ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T10:50:32.608121Z","caller":"traceutil/trace.go:171","msg":"trace[1523645049] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"154.676534ms","start":"2026-04-14T10:50:32.453427Z","end":"2026-04-14T10:50:32.608104Z","steps":["trace[1523645049] 'process raft request' (duration: 154.541641ms)"],"step_count":1}