{"level":"warn","ts":"2026-04-28T13:54:39.26047Z","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-28T13:54:39.260572Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.134: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.134:2380","--initial-cluster=instance=https://199.19.213.134:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.134:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.134: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-28T13:54:39.260663Z","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-28T13:54:39.260682Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.134:2380"]} {"level":"info","ts":"2026-04-28T13:54:39.26071Z","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-28T13:54:39.261548Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.134:2379"]} {"level":"info","ts":"2026-04-28T13:54:39.261646Z","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.134:2380"],"listen-peer-urls":["https://199.19.213.134:2380"],"advertise-client-urls":["https://199.19.213.134:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.134:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.134: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-28T13:54:39.268083Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.196492ms"} {"level":"info","ts":"2026-04-28T13:54:39.281673Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"92da22d3868c86fb","cluster-id":"84450b28e935eb90"} {"level":"info","ts":"2026-04-28T13:54:39.281795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T13:54:39.281831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became follower at term 0"} {"level":"info","ts":"2026-04-28T13:54:39.281957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 92da22d3868c86fb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T13:54:39.281972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became follower at term 1"} {"level":"info","ts":"2026-04-28T13:54:39.28204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb switched to configuration voters=(10581808566351202043)"} {"level":"warn","ts":"2026-04-28T13:54:39.290504Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T13:54:39.291821Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T13:54:39.293153Z","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-28T13:54:39.295318Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"92da22d3868c86fb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T13:54:39.295468Z","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-28T13:54:39.295576Z","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-28T13:54:39.295587Z","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-28T13:54:39.295747Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"92da22d3868c86fb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T13:54:39.296792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb switched to configuration voters=(10581808566351202043)"} {"level":"info","ts":"2026-04-28T13:54:39.296917Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"84450b28e935eb90","local-member-id":"92da22d3868c86fb","added-peer-id":"92da22d3868c86fb","added-peer-peer-urls":["https://199.19.213.134:2380"]} {"level":"info","ts":"2026-04-28T13:54:39.30184Z","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-28T13:54:39.302056Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"92da22d3868c86fb","initial-advertise-peer-urls":["https://199.19.213.134:2380"],"listen-peer-urls":["https://199.19.213.134:2380"],"advertise-client-urls":["https://199.19.213.134:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.134:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T13:54:39.302089Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T13:54:39.302574Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.134:2380"} {"level":"info","ts":"2026-04-28T13:54:39.302603Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.134:2380"} {"level":"info","ts":"2026-04-28T13:54:39.883509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T13:54:39.883564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T13:54:39.883629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb received MsgPreVoteResp from 92da22d3868c86fb at term 1"} {"level":"info","ts":"2026-04-28T13:54:39.883646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became candidate at term 2"} {"level":"info","ts":"2026-04-28T13:54:39.883652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb received MsgVoteResp from 92da22d3868c86fb at term 2"} {"level":"info","ts":"2026-04-28T13:54:39.883667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became leader at term 2"} {"level":"info","ts":"2026-04-28T13:54:39.883693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 92da22d3868c86fb elected leader 92da22d3868c86fb at term 2"} {"level":"info","ts":"2026-04-28T13:54:39.884762Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"92da22d3868c86fb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.134:2379]}","request-path":"/0/members/92da22d3868c86fb/attributes","cluster-id":"84450b28e935eb90","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T13:54:39.884801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:54:39.884859Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T13:54:39.886037Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:54:39.886741Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T13:54:39.886767Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T13:54:39.887172Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"84450b28e935eb90","local-member-id":"92da22d3868c86fb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:54:39.887237Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:54:39.887257Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T13:54:39.887737Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.134:2379"} {"level":"info","ts":"2026-04-28T13:54:39.887886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T13:54:55.633363Z","caller":"traceutil/trace.go:171","msg":"trace[842174099] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"120.001346ms","start":"2026-04-28T13:54:55.513322Z","end":"2026-04-28T13:54:55.633323Z","steps":["trace[842174099] 'process raft request' (duration: 119.870683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:56.251937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.195584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:54:56.25217Z","caller":"traceutil/trace.go:171","msg":"trace[732442211] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"166.446022ms","start":"2026-04-28T13:54:56.085692Z","end":"2026-04-28T13:54:56.252138Z","steps":["trace[732442211] 'process raft request' (duration: 48.551853ms)","trace[732442211] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/kube-system/coredns-67659f764b-8q6xz.18aa89c83e4c2203; req_size:827; } (duration: 116.866327ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:56.252279Z","caller":"traceutil/trace.go:171","msg":"trace[2118331084] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"164.771895ms","start":"2026-04-28T13:54:56.08749Z","end":"2026-04-28T13:54:56.252262Z","steps":["trace[2118331084] 'process raft request' (duration: 164.726824ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:56.252661Z","caller":"traceutil/trace.go:171","msg":"trace[1594307585] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"165.386338ms","start":"2026-04-28T13:54:56.087264Z","end":"2026-04-28T13:54:56.25265Z","steps":["trace[1594307585] 'process raft request' (duration: 164.884127ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:56.252637Z","caller":"traceutil/trace.go:171","msg":"trace[820972372] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"165.46271ms","start":"2026-04-28T13:54:56.087151Z","end":"2026-04-28T13:54:56.252613Z","steps":["trace[820972372] 'process raft request' (duration: 164.909578ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:56.612167Z","caller":"traceutil/trace.go:171","msg":"trace[1619088280] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"123.494614ms","start":"2026-04-28T13:54:56.488651Z","end":"2026-04-28T13:54:56.612146Z","steps":["trace[1619088280] 'process raft request' (duration: 72.829994ms)","trace[1619088280] 'compare' (duration: 50.479126ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:56.612229Z","caller":"traceutil/trace.go:171","msg":"trace[1169621254] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"121.351448ms","start":"2026-04-28T13:54:56.490861Z","end":"2026-04-28T13:54:56.612213Z","steps":["trace[1169621254] 'process raft request' (duration: 121.320276ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:56.612491Z","caller":"traceutil/trace.go:171","msg":"trace[1396422597] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"123.119137ms","start":"2026-04-28T13:54:56.489366Z","end":"2026-04-28T13:54:56.612485Z","steps":["trace[1396422597] 'process raft request' (duration: 122.771088ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:56.621056Z","caller":"traceutil/trace.go:171","msg":"trace[1002567092] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"104.43136ms","start":"2026-04-28T13:54:56.516602Z","end":"2026-04-28T13:54:56.621034Z","steps":["trace[1002567092] 'process raft request' (duration: 104.311198ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:56.819303Z","caller":"traceutil/trace.go:171","msg":"trace[884169704] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"162.356521ms","start":"2026-04-28T13:54:56.656926Z","end":"2026-04-28T13:54:56.819282Z","steps":["trace[884169704] 'process raft request' (duration: 97.161447ms)","trace[884169704] 'compare' (duration: 64.952559ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:56.819461Z","caller":"traceutil/trace.go:171","msg":"trace[1317013880] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:413; }","duration":"110.807471ms","start":"2026-04-28T13:54:56.70863Z","end":"2026-04-28T13:54:56.819438Z","steps":["trace[1317013880] 'read index received' (duration: 45.363432ms)","trace[1317013880] 'applied index is now lower than readState.Index' (duration: 65.442739ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:56.819661Z","caller":"traceutil/trace.go:171","msg":"trace[308006870] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"161.510602ms","start":"2026-04-28T13:54:56.658141Z","end":"2026-04-28T13:54:56.819651Z","steps":["trace[308006870] 'process raft request' (duration: 161.075821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:56.819679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.028827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-04-28T13:54:56.819752Z","caller":"traceutil/trace.go:171","msg":"trace[1550786861] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:402; }","duration":"111.142569ms","start":"2026-04-28T13:54:56.708592Z","end":"2026-04-28T13:54:56.819734Z","steps":["trace[1550786861] 'agreement among raft nodes before linearized reading' (duration: 110.971315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:57.068636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.202028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:54:57.068723Z","caller":"traceutil/trace.go:171","msg":"trace[2104648621] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"165.04357ms","start":"2026-04-28T13:54:56.903664Z","end":"2026-04-28T13:54:57.068708Z","steps":["trace[2104648621] 'process raft request' (duration: 45.605017ms)","trace[2104648621] 'compare' (duration: 119.123286ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:54:58.653512Z","caller":"traceutil/trace.go:171","msg":"trace[688969711] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"185.18519ms","start":"2026-04-28T13:54:58.468294Z","end":"2026-04-28T13:54:58.65348Z","steps":["trace[688969711] 'process raft request' (duration: 184.949785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:59.33331Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686704529,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T13:54:59.589126Z","caller":"traceutil/trace.go:171","msg":"trace[1500004851] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"756.80818ms","start":"2026-04-28T13:54:58.832286Z","end":"2026-04-28T13:54:59.589094Z","steps":["trace[1500004851] 'read index received' (duration: 756.530744ms)","trace[1500004851] 'applied index is now lower than readState.Index' (duration: 276.016µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:54:59.589504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.282956ms","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-28T13:54:59.589536Z","caller":"traceutil/trace.go:171","msg":"trace[1780472713] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"927.251201ms","start":"2026-04-28T13:54:58.662271Z","end":"2026-04-28T13:54:59.589522Z","steps":["trace[1780472713] 'process raft request' (duration: 926.651828ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:54:59.589548Z","caller":"traceutil/trace.go:171","msg":"trace[751565578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:409; }","duration":"165.374408ms","start":"2026-04-28T13:54:59.424162Z","end":"2026-04-28T13:54:59.589537Z","steps":["trace[751565578] 'agreement among raft nodes before linearized reading' (duration: 165.261976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:59.589528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.254165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-28T13:54:59.589636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:58.66225Z","time spent":"927.318352ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:54:59.589966Z","caller":"traceutil/trace.go:171","msg":"trace[522826971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"516.384478ms","start":"2026-04-28T13:54:59.073201Z","end":"2026-04-28T13:54:59.589586Z","steps":["trace[522826971] 'agreement among raft nodes before linearized reading' (duration: 516.224664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:59.590026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:59.073174Z","time spent":"516.838017ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T13:54:59.590575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.275513ms","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-28T13:54:59.591971Z","caller":"traceutil/trace.go:171","msg":"trace[2130515829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"759.703144ms","start":"2026-04-28T13:54:58.832242Z","end":"2026-04-28T13:54:59.591946Z","steps":["trace[2130515829] 'agreement among raft nodes before linearized reading' (duration: 758.263093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:54:59.592148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:58.83222Z","time spent":"759.803596ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T13:55:01.025566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.806307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:55:01.02582Z","caller":"traceutil/trace.go:171","msg":"trace[428430518] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.428763007s","start":"2026-04-28T13:54:59.597038Z","end":"2026-04-28T13:55:01.025801Z","steps":["trace[428430518] 'process raft request' (duration: 1.428637244s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:01.025895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:59.597011Z","time spent":"1.428851359s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:01.025892Z","caller":"traceutil/trace.go:171","msg":"trace[1528049764] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.430259871s","start":"2026-04-28T13:54:59.595598Z","end":"2026-04-28T13:55:01.025858Z","steps":["trace[1528049764] 'process raft request' (duration: 540.084206ms)","trace[1528049764] 'compare' (duration: 889.541791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:01.026049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:59.595576Z","time spent":"1.430395783s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:01.026196Z","caller":"traceutil/trace.go:171","msg":"trace[2061469155] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:423; }","duration":"1.378651629s","start":"2026-04-28T13:54:59.647534Z","end":"2026-04-28T13:55:01.026186Z","steps":["trace[2061469155] 'read index received' (duration: 488.173378ms)","trace[2061469155] 'applied index is now lower than readState.Index' (duration: 890.477211ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:01.026366Z","caller":"traceutil/trace.go:171","msg":"trace[469198180] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"1.411147504s","start":"2026-04-28T13:54:59.615207Z","end":"2026-04-28T13:55:01.026354Z","steps":["trace[469198180] 'process raft request' (duration: 1.410562871s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:01.026425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:59.615188Z","time spent":"1.411207296s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:55:01.0266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379062928s","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-28T13:55:01.026664Z","caller":"traceutil/trace.go:171","msg":"trace[627347761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"1.37916875s","start":"2026-04-28T13:54:59.647482Z","end":"2026-04-28T13:55:01.02665Z","steps":["trace[627347761] 'agreement among raft nodes before linearized reading' (duration: 1.379075178s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:01.026676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.718896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:55:01.026693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:54:59.647459Z","time spent":"1.379227682s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T13:55:01.026718Z","caller":"traceutil/trace.go:171","msg":"trace[223700631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"425.759566ms","start":"2026-04-28T13:55:00.600946Z","end":"2026-04-28T13:55:01.026706Z","steps":["trace[223700631] 'agreement among raft nodes before linearized reading' (duration: 425.696605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:01.026751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:00.600896Z","time spent":"425.848798ms","remote":"127.0.0.1:33884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T13:55:10.383275Z","caller":"traceutil/trace.go:171","msg":"trace[1930029757] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"399.105961ms","start":"2026-04-28T13:55:09.984131Z","end":"2026-04-28T13:55:10.383236Z","steps":["trace[1930029757] 'process raft request' (duration: 398.372785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:10.383481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:09.984111Z","time spent":"399.239413ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:10.389962Z","caller":"traceutil/trace.go:171","msg":"trace[1938578806] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"402.470157ms","start":"2026-04-28T13:55:09.987452Z","end":"2026-04-28T13:55:10.389922Z","steps":["trace[1938578806] 'process raft request' (duration: 402.248982ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:10.390023Z","caller":"traceutil/trace.go:171","msg":"trace[1487670946] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"402.441256ms","start":"2026-04-28T13:55:09.987533Z","end":"2026-04-28T13:55:10.389974Z","steps":["trace[1487670946] 'process raft request' (duration: 402.295223ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:10.390092Z","caller":"traceutil/trace.go:171","msg":"trace[537355305] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"391.978663ms","start":"2026-04-28T13:55:09.99809Z","end":"2026-04-28T13:55:10.390069Z","steps":["trace[537355305] 'process raft request' (duration: 391.83779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:10.390192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:09.987528Z","time spent":"402.586359ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:10.390176Z","caller":"traceutil/trace.go:171","msg":"trace[671722912] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"392.038424ms","start":"2026-04-28T13:55:09.998115Z","end":"2026-04-28T13:55:10.390153Z","steps":["trace[671722912] 'process raft request' (duration: 391.86079ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:10.390238Z","caller":"traceutil/trace.go:171","msg":"trace[1300872871] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"381.377866ms","start":"2026-04-28T13:55:10.00884Z","end":"2026-04-28T13:55:10.390218Z","steps":["trace[1300872871] 'process raft request' (duration: 381.220743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:10.390295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:10.008818Z","time spent":"381.453967ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:55:10.390318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:09.998108Z","time spent":"392.145656ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:10.390378Z","caller":"traceutil/trace.go:171","msg":"trace[2136668594] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"357.634986ms","start":"2026-04-28T13:55:10.032731Z","end":"2026-04-28T13:55:10.390366Z","steps":["trace[2136668594] 'process raft request' (duration: 357.428812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:10.390232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:09.987418Z","time spent":"402.61171ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:10.390436Z","caller":"traceutil/trace.go:171","msg":"trace[1271071148] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"371.717101ms","start":"2026-04-28T13:55:10.018707Z","end":"2026-04-28T13:55:10.390424Z","steps":["trace[1271071148] 'process raft request' (duration: 371.398634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:10.390452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:10.032713Z","time spent":"357.711889ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:55:10.390499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:10.018562Z","time spent":"371.906395ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:10.390489Z","caller":"traceutil/trace.go:171","msg":"trace[1038754219] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"221.980901ms","start":"2026-04-28T13:55:10.168484Z","end":"2026-04-28T13:55:10.390464Z","steps":["trace[1038754219] 'process raft request' (duration: 221.904119ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:10.390584Z","caller":"traceutil/trace.go:171","msg":"trace[1851673902] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"351.631203ms","start":"2026-04-28T13:55:10.038945Z","end":"2026-04-28T13:55:10.390576Z","steps":["trace[1851673902] 'process raft request' (duration: 351.279185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:10.390631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:10.038905Z","time spent":"351.707015ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:10.39063Z","caller":"traceutil/trace.go:171","msg":"trace[284985683] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"327.76236ms","start":"2026-04-28T13:55:10.062858Z","end":"2026-04-28T13:55:10.390621Z","steps":["trace[284985683] 'process raft request' (duration: 327.439433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:10.390678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:10.062842Z","time spent":"327.818221ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:55:10.390192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:09.998068Z","time spent":"392.066064ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:10.390116Z","caller":"traceutil/trace.go:171","msg":"trace[429378346] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"392.019543ms","start":"2026-04-28T13:55:09.998025Z","end":"2026-04-28T13:55:10.390044Z","steps":["trace[429378346] 'process raft request' (duration: 391.85097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:10.390956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:09.998001Z","time spent":"392.900223ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:11.779992Z","caller":"traceutil/trace.go:171","msg":"trace[797084522] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"113.787908ms","start":"2026-04-28T13:55:11.666179Z","end":"2026-04-28T13:55:11.779967Z","steps":["trace[797084522] 'process raft request' (duration: 109.578634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:11.780042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.364749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-04-28T13:55:11.780153Z","caller":"traceutil/trace.go:171","msg":"trace[1167247420] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:544; }","duration":"139.536112ms","start":"2026-04-28T13:55:11.640596Z","end":"2026-04-28T13:55:11.780132Z","steps":["trace[1167247420] 'range keys from in-memory index tree' (duration: 139.146734ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:17.317377Z","caller":"traceutil/trace.go:171","msg":"trace[495512896] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"310.478585ms","start":"2026-04-28T13:55:17.006874Z","end":"2026-04-28T13:55:17.317353Z","steps":["trace[495512896] 'process raft request' (duration: 310.080355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:17.317521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:17.006849Z","time spent":"310.581247ms","remote":"127.0.0.1:33976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:17.503619Z","caller":"traceutil/trace.go:171","msg":"trace[436308215] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"186.268564ms","start":"2026-04-28T13:55:17.31733Z","end":"2026-04-28T13:55:17.503599Z","steps":["trace[436308215] 'read index received' (duration: 186.246824ms)","trace[436308215] 'applied index is now lower than readState.Index' (duration: 20.79µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:17.503797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.074139ms","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-28T13:55:17.503828Z","caller":"traceutil/trace.go:171","msg":"trace[1575755277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"246.15169ms","start":"2026-04-28T13:55:17.25767Z","end":"2026-04-28T13:55:17.503821Z","steps":["trace[1575755277] 'agreement among raft nodes before linearized reading' (duration: 245.998757ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:17.503729Z","caller":"traceutil/trace.go:171","msg":"trace[1763045533] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"252.195915ms","start":"2026-04-28T13:55:17.251514Z","end":"2026-04-28T13:55:17.50371Z","steps":["trace[1763045533] 'process raft request' (duration: 251.95946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:17.504107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.030424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T13:55:17.504209Z","caller":"traceutil/trace.go:171","msg":"trace[2027705162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"245.29515ms","start":"2026-04-28T13:55:17.258893Z","end":"2026-04-28T13:55:17.504188Z","steps":["trace[2027705162] 'agreement among raft nodes before linearized reading' (duration: 244.781649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:17.504574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.627068ms","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-28T13:55:17.50465Z","caller":"traceutil/trace.go:171","msg":"trace[944720286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"245.7096ms","start":"2026-04-28T13:55:17.25892Z","end":"2026-04-28T13:55:17.504629Z","steps":["trace[944720286] 'agreement among raft nodes before linearized reading' (duration: 244.691867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:18.546521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.810508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:55:18.546702Z","caller":"traceutil/trace.go:171","msg":"trace[1528678739] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.170138157s","start":"2026-04-28T13:55:17.376548Z","end":"2026-04-28T13:55:18.546687Z","steps":["trace[1528678739] 'process raft request' (duration: 1.170087926s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:18.546762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:17.376525Z","time spent":"1.170213459s","remote":"127.0.0.1:34140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:18.547542Z","caller":"traceutil/trace.go:171","msg":"trace[74802189] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.224884139s","start":"2026-04-28T13:55:17.322019Z","end":"2026-04-28T13:55:18.546903Z","steps":["trace[74802189] 'process raft request' (duration: 398.604471ms)","trace[74802189] 'compare' (duration: 825.653634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:55:18.547704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:17.322002Z","time spent":"1.225624236s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:18.626699Z","caller":"traceutil/trace.go:171","msg":"trace[1896525480] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.118923316s","start":"2026-04-28T13:55:17.507751Z","end":"2026-04-28T13:55:18.626674Z","steps":["trace[1896525480] 'process raft request' (duration: 1.118438095s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:18.626856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:17.507727Z","time spent":"1.119045078s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:18.627392Z","caller":"traceutil/trace.go:171","msg":"trace[1450828553] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:599; }","duration":"1.02806278s","start":"2026-04-28T13:55:17.599288Z","end":"2026-04-28T13:55:18.627351Z","steps":["trace[1450828553] 'read index received' (duration: 121.346778ms)","trace[1450828553] 'applied index is now lower than readState.Index' (duration: 906.715072ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:18.627435Z","caller":"traceutil/trace.go:171","msg":"trace[1393793971] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.119624041s","start":"2026-04-28T13:55:17.507776Z","end":"2026-04-28T13:55:18.6274Z","steps":["trace[1393793971] 'process raft request' (duration: 1.118712061s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:18.627497Z","caller":"traceutil/trace.go:171","msg":"trace[1606404222] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.119413237s","start":"2026-04-28T13:55:17.508073Z","end":"2026-04-28T13:55:18.627487Z","steps":["trace[1606404222] 'process raft request' (duration: 1.119209042s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:18.627576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028295765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:55:18.627613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:17.508057Z","time spent":"1.119479578s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:18.627636Z","caller":"traceutil/trace.go:171","msg":"trace[1925913347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"1.028398948s","start":"2026-04-28T13:55:17.599224Z","end":"2026-04-28T13:55:18.627623Z","steps":["trace[1925913347] 'agreement among raft nodes before linearized reading' (duration: 1.028315436s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:18.627626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:17.507744Z","time spent":"1.119777654s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:55:18.627673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:17.599198Z","time spent":"1.028467708s","remote":"127.0.0.1:33884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:55:18.711853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.374747ms","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-28T13:55:18.711931Z","caller":"traceutil/trace.go:171","msg":"trace[2024872369] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:582; }","duration":"160.546521ms","start":"2026-04-28T13:55:18.551367Z","end":"2026-04-28T13:55:18.711914Z","steps":["trace[2024872369] 'agreement among raft nodes before linearized reading' (duration: 160.400198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:18.712033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.738019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8rmlv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T13:55:18.712111Z","caller":"traceutil/trace.go:171","msg":"trace[512120620] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8rmlv; range_end:; response_count:1; response_revision:582; }","duration":"457.873272ms","start":"2026-04-28T13:55:18.254219Z","end":"2026-04-28T13:55:18.712092Z","steps":["trace[512120620] 'agreement among raft nodes before linearized reading' (duration: 457.623346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:18.712158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:18.2542Z","time spent":"457.949124ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-8rmlv\" "} {"level":"info","ts":"2026-04-28T13:55:19.425165Z","caller":"traceutil/trace.go:171","msg":"trace[1782711010] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"699.607714ms","start":"2026-04-28T13:55:18.72553Z","end":"2026-04-28T13:55:19.425138Z","steps":["trace[1782711010] 'process raft request' (duration: 699.442191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:19.425362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:18.725518Z","time spent":"699.734447ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:55:20.256734Z","caller":"traceutil/trace.go:171","msg":"trace[781810757] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.530231519s","start":"2026-04-28T13:55:18.726476Z","end":"2026-04-28T13:55:20.256707Z","steps":["trace[781810757] 'process raft request' (duration: 1.529962084s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.256973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:18.726455Z","time spent":"1.530429373s","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:20.267909Z","caller":"traceutil/trace.go:171","msg":"trace[1981176662] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:607; }","duration":"487.283498ms","start":"2026-04-28T13:55:19.780607Z","end":"2026-04-28T13:55:20.267891Z","steps":["trace[1981176662] 'read index received' (duration: 476.086648ms)","trace[1981176662] 'applied index is now lower than readState.Index' (duration: 11.19603ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:55:20.267959Z","caller":"traceutil/trace.go:171","msg":"trace[847817261] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"836.454886ms","start":"2026-04-28T13:55:19.431491Z","end":"2026-04-28T13:55:20.267946Z","steps":["trace[847817261] 'process raft request' (duration: 836.225831ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:20.267996Z","caller":"traceutil/trace.go:171","msg":"trace[838707616] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"835.530975ms","start":"2026-04-28T13:55:19.432458Z","end":"2026-04-28T13:55:20.267989Z","steps":["trace[838707616] 'process raft request' (duration: 835.366181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.43147Z","time spent":"836.520317ms","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:55:20.268137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.520323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.268156Z","caller":"traceutil/trace.go:171","msg":"trace[1969446459] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"836.554518ms","start":"2026-04-28T13:55:19.431536Z","end":"2026-04-28T13:55:20.268091Z","steps":["trace[1969446459] 'process raft request' (duration: 836.264922ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:20.268207Z","caller":"traceutil/trace.go:171","msg":"trace[367866331] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"834.939312ms","start":"2026-04-28T13:55:19.433261Z","end":"2026-04-28T13:55:20.2682Z","steps":["trace[367866331] 'process raft request' (duration: 834.603164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.433251Z","time spent":"834.970782ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:55:20.268266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.919103ms","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":"info","ts":"2026-04-28T13:55:20.268294Z","caller":"traceutil/trace.go:171","msg":"trace[991798252] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:588; }","duration":"292.978874ms","start":"2026-04-28T13:55:19.975308Z","end":"2026-04-28T13:55:20.268287Z","steps":["trace[991798252] 'agreement among raft nodes before linearized reading' (duration: 292.935894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.431518Z","time spent":"836.732322ms","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:55:20.268455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.399378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:55:20.26844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.538454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:55:20.268881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.432447Z","time spent":"835.568776ms","remote":"127.0.0.1:34140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:20.268899Z","caller":"traceutil/trace.go:171","msg":"trace[1586175653] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:588; }","duration":"488.158017ms","start":"2026-04-28T13:55:19.780724Z","end":"2026-04-28T13:55:20.268882Z","steps":["trace[1586175653] 'agreement among raft nodes before linearized reading' (duration: 487.506232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.780708Z","time spent":"488.223719ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:55:20.268477Z","caller":"traceutil/trace.go:171","msg":"trace[2115555550] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:588; }","duration":"386.45914ms","start":"2026-04-28T13:55:19.882011Z","end":"2026-04-28T13:55:20.268471Z","steps":["trace[2115555550] 'agreement among raft nodes before linearized reading' (duration: 386.421379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.269001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.881954Z","time spent":"387.038092ms","remote":"127.0.0.1:39870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-28T13:55:20.268509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.199909ms","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":"info","ts":"2026-04-28T13:55:20.269106Z","caller":"traceutil/trace.go:171","msg":"trace[2124101925] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:588; }","duration":"293.816803ms","start":"2026-04-28T13:55:19.975281Z","end":"2026-04-28T13:55:20.269098Z","steps":["trace[2124101925] 'agreement among raft nodes before linearized reading' (duration: 293.20966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.841323ms","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":"info","ts":"2026-04-28T13:55:20.269143Z","caller":"traceutil/trace.go:171","msg":"trace[1768828811] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:588; }","duration":"311.446336ms","start":"2026-04-28T13:55:19.957688Z","end":"2026-04-28T13:55:20.269134Z","steps":["trace[1768828811] 'agreement among raft nodes before linearized reading' (duration: 310.832943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.269171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.957677Z","time spent":"311.487127ms","remote":"127.0.0.1:39874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-28T13:55:20.268559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.577169ms","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-28T13:55:20.269326Z","caller":"traceutil/trace.go:171","msg":"trace[1880176686] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:588; }","duration":"312.333106ms","start":"2026-04-28T13:55:19.956977Z","end":"2026-04-28T13:55:20.26931Z","steps":["trace[1880176686] 'agreement among raft nodes before linearized reading' (duration: 311.567599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.269372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.956955Z","time spent":"312.400027ms","remote":"127.0.0.1:39874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-28T13:55:20.268563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.441303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.269443Z","caller":"traceutil/trace.go:171","msg":"trace[1975925048] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"415.357634ms","start":"2026-04-28T13:55:19.854076Z","end":"2026-04-28T13:55:20.269434Z","steps":["trace[1975925048] 'agreement among raft nodes before linearized reading' (duration: 414.446004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.269464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.854061Z","time spent":"415.395874ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-28T13:55:20.268582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.61807ms","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-28T13:55:20.269545Z","caller":"traceutil/trace.go:171","msg":"trace[1801276143] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:588; }","duration":"312.589561ms","start":"2026-04-28T13:55:19.95695Z","end":"2026-04-28T13:55:20.269539Z","steps":["trace[1801276143] 'agreement among raft nodes before linearized reading' (duration: 311.62031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.269564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.956945Z","time spent":"312.612862ms","remote":"127.0.0.1:39874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:55:20.268608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.815975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.269625Z","caller":"traceutil/trace.go:171","msg":"trace[379501426] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:588; }","duration":"386.843318ms","start":"2026-04-28T13:55:19.882774Z","end":"2026-04-28T13:55:20.269617Z","steps":["trace[379501426] 'agreement among raft nodes before linearized reading' (duration: 385.821855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.269653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.882768Z","time spent":"386.872578ms","remote":"127.0.0.1:39870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-28T13:55:20.268605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.475594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.269753Z","caller":"traceutil/trace.go:171","msg":"trace[1793819243] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"415.62085ms","start":"2026-04-28T13:55:19.854121Z","end":"2026-04-28T13:55:20.269742Z","steps":["trace[1793819243] 'agreement among raft nodes before linearized reading' (duration: 414.465304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.269772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.854101Z","time spent":"415.66402ms","remote":"127.0.0.1:39860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:55:20.268181Z","caller":"traceutil/trace.go:171","msg":"trace[2057111280] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:588; }","duration":"487.589925ms","start":"2026-04-28T13:55:19.780579Z","end":"2026-04-28T13:55:20.268169Z","steps":["trace[2057111280] 'agreement among raft nodes before linearized reading' (duration: 487.517743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.269958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.780542Z","time spent":"489.404725ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-04-28T13:55:20.26861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.997293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.27004Z","caller":"traceutil/trace.go:171","msg":"trace[76927289] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"415.437086ms","start":"2026-04-28T13:55:19.854594Z","end":"2026-04-28T13:55:20.270031Z","steps":["trace[76927289] 'agreement among raft nodes before linearized reading' (duration: 413.990703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.27006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.85459Z","time spent":"415.462926ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-28T13:55:20.268632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.527511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.270145Z","caller":"traceutil/trace.go:171","msg":"trace[1090793672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:588; }","duration":"388.060515ms","start":"2026-04-28T13:55:19.882079Z","end":"2026-04-28T13:55:20.270139Z","steps":["trace[1090793672] 'agreement among raft nodes before linearized reading' (duration: 386.540541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.270163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.882066Z","time spent":"388.090337ms","remote":"127.0.0.1:39870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:55:20.268642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.614912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.270238Z","caller":"traceutil/trace.go:171","msg":"trace[658843912] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"457.226068ms","start":"2026-04-28T13:55:19.813008Z","end":"2026-04-28T13:55:20.270234Z","steps":["trace[658843912] 'agreement among raft nodes before linearized reading' (duration: 455.621162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.270254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.812997Z","time spent":"457.251579ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-28T13:55:20.268645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.470232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.270345Z","caller":"traceutil/trace.go:171","msg":"trace[436562193] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:588; }","duration":"489.191951ms","start":"2026-04-28T13:55:19.781148Z","end":"2026-04-28T13:55:20.27034Z","steps":["trace[436562193] 'agreement among raft nodes before linearized reading' (duration: 487.482392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.270363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.781137Z","time spent":"489.220551ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-04-28T13:55:20.26868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.734491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.270459Z","caller":"traceutil/trace.go:171","msg":"trace[1217832262] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:588; }","duration":"121.519491ms","start":"2026-04-28T13:55:20.148934Z","end":"2026-04-28T13:55:20.270453Z","steps":["trace[1217832262] 'agreement among raft nodes before linearized reading' (duration: 119.726521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.406486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.27055Z","caller":"traceutil/trace.go:171","msg":"trace[427013054] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:588; }","duration":"457.332229ms","start":"2026-04-28T13:55:19.813213Z","end":"2026-04-28T13:55:20.270546Z","steps":["trace[427013054] 'agreement among raft nodes before linearized reading' (duration: 455.441647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.270566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:19.813192Z","time spent":"457.368741ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:55:20.268698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.983875ms","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":"info","ts":"2026-04-28T13:55:20.270658Z","caller":"traceutil/trace.go:171","msg":"trace[2053577572] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:588; }","duration":"238.96401ms","start":"2026-04-28T13:55:20.031688Z","end":"2026-04-28T13:55:20.270652Z","steps":["trace[2053577572] 'agreement among raft nodes before linearized reading' (duration: 236.962084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.467887ms","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-28T13:55:20.270796Z","caller":"traceutil/trace.go:171","msg":"trace[33299182] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:588; }","duration":"122.561604ms","start":"2026-04-28T13:55:20.148228Z","end":"2026-04-28T13:55:20.27079Z","steps":["trace[33299182] 'agreement among raft nodes before linearized reading' (duration: 120.482307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.011446ms","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":"info","ts":"2026-04-28T13:55:20.270895Z","caller":"traceutil/trace.go:171","msg":"trace[1163526987] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:588; }","duration":"239.203145ms","start":"2026-04-28T13:55:20.031688Z","end":"2026-04-28T13:55:20.270891Z","steps":["trace[1163526987] 'agreement among raft nodes before linearized reading' (duration: 237.016476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.26875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.515988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:55:20.270986Z","caller":"traceutil/trace.go:171","msg":"trace[1701949148] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:588; }","duration":"122.774159ms","start":"2026-04-28T13:55:20.148207Z","end":"2026-04-28T13:55:20.270981Z","steps":["trace[1701949148] 'agreement among raft nodes before linearized reading' (duration: 120.528848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.901292ms","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-28T13:55:20.271075Z","caller":"traceutil/trace.go:171","msg":"trace[487456519] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:588; }","duration":"295.209794ms","start":"2026-04-28T13:55:19.975861Z","end":"2026-04-28T13:55:20.27107Z","steps":["trace[487456519] 'agreement among raft nodes before linearized reading' (duration: 292.900422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:20.268776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.529025ms","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":"info","ts":"2026-04-28T13:55:20.271169Z","caller":"traceutil/trace.go:171","msg":"trace[79015463] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:588; }","duration":"238.933809ms","start":"2026-04-28T13:55:20.032231Z","end":"2026-04-28T13:55:20.271165Z","steps":["trace[79015463] 'agreement among raft nodes before linearized reading' (duration: 236.532015ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:22.560254Z","caller":"traceutil/trace.go:171","msg":"trace[1786063134] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"180.25878ms","start":"2026-04-28T13:55:22.37997Z","end":"2026-04-28T13:55:22.560229Z","steps":["trace[1786063134] 'process raft request' (duration: 180.119487ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:55:36.206561Z","caller":"traceutil/trace.go:171","msg":"trace[878053509] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"836.395096ms","start":"2026-04-28T13:55:35.370143Z","end":"2026-04-28T13:55:36.206538Z","steps":["trace[878053509] 'process raft request' (duration: 836.245243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:36.206739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:35.370128Z","time spent":"836.515828ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:55:36.213044Z","caller":"traceutil/trace.go:171","msg":"trace[1810187342] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"317.569845ms","start":"2026-04-28T13:55:35.895451Z","end":"2026-04-28T13:55:36.213021Z","steps":["trace[1810187342] 'process raft request' (duration: 317.418652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:55:36.213192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:55:35.895425Z","time spent":"317.688398ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:10.820806Z","caller":"traceutil/trace.go:171","msg":"trace[1602879711] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"393.763594ms","start":"2026-04-28T13:56:10.427026Z","end":"2026-04-28T13:56:10.82079Z","steps":["trace[1602879711] 'process raft request' (duration: 393.638671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:10.820925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:10.427009Z","time spent":"393.857026ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:10.821025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.022539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:56:10.821101Z","caller":"traceutil/trace.go:171","msg":"trace[1364103472] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:955; }","duration":"319.140921ms","start":"2026-04-28T13:56:10.50195Z","end":"2026-04-28T13:56:10.821091Z","steps":["trace[1364103472] 'agreement among raft nodes before linearized reading' (duration: 318.969068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:10.821151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:10.501893Z","time spent":"319.246905ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:56:10.820862Z","caller":"traceutil/trace.go:171","msg":"trace[54225833] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"318.836445ms","start":"2026-04-28T13:56:10.501967Z","end":"2026-04-28T13:56:10.820803Z","steps":["trace[54225833] 'read index received' (duration: 318.789794ms)","trace[54225833] 'applied index is now lower than readState.Index' (duration: 44.221µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:10.854809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.326901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:56:10.8549Z","caller":"traceutil/trace.go:171","msg":"trace[380357456] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:956; }","duration":"324.494545ms","start":"2026-04-28T13:56:10.530388Z","end":"2026-04-28T13:56:10.854882Z","steps":["trace[380357456] 'agreement among raft nodes before linearized reading' (duration: 324.337141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:10.854954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:10.530365Z","time spent":"324.573987ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:56:10.854921Z","caller":"traceutil/trace.go:171","msg":"trace[1730680233] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"318.426046ms","start":"2026-04-28T13:56:10.536469Z","end":"2026-04-28T13:56:10.854895Z","steps":["trace[1730680233] 'process raft request' (duration: 318.125799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:10.855099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:10.536455Z","time spent":"318.563039ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:11.361841Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686705668,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:56:11.521153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.623279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:11.521253Z","caller":"traceutil/trace.go:171","msg":"trace[131038970] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"661.444614ms","start":"2026-04-28T13:56:10.859789Z","end":"2026-04-28T13:56:11.521234Z","steps":["trace[131038970] 'process raft request' (duration: 506.666703ms)","trace[131038970] 'compare' (duration: 154.080315ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:11.521384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:10.859773Z","time spent":"661.510416ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:12.412651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.599438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:56:12.41274Z","caller":"traceutil/trace.go:171","msg":"trace[548980917] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:993; }","duration":"1.550995873s","start":"2026-04-28T13:56:10.861731Z","end":"2026-04-28T13:56:12.412727Z","steps":["trace[548980917] 'read index received' (duration: 504.72762ms)","trace[548980917] 'applied index is now lower than readState.Index' (duration: 1.046267193s)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:12.41281Z","caller":"traceutil/trace.go:171","msg":"trace[1704126114] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.55176206s","start":"2026-04-28T13:56:10.861019Z","end":"2026-04-28T13:56:12.412781Z","steps":["trace[1704126114] 'process raft request' (duration: 1.246937887s)","trace[1704126114] 'compare' (duration: 304.480085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:12.412935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.551204348s","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-28T13:56:12.412969Z","caller":"traceutil/trace.go:171","msg":"trace[1568230346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"1.551256758s","start":"2026-04-28T13:56:10.861702Z","end":"2026-04-28T13:56:12.412959Z","steps":["trace[1568230346] 'agreement among raft nodes before linearized reading' (duration: 1.551101295s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:12.412976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:10.861001Z","time spent":"1.551900063s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:12.413001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:10.861695Z","time spent":"1.551297719s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T13:56:12.413163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542898997s","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-28T13:56:12.413185Z","caller":"traceutil/trace.go:171","msg":"trace[1202766447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"1.542943398s","start":"2026-04-28T13:56:10.870236Z","end":"2026-04-28T13:56:12.413179Z","steps":["trace[1202766447] 'agreement among raft nodes before linearized reading' (duration: 1.542877297s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:12.413209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:10.870223Z","time spent":"1.542977349s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T13:56:12.413379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.792769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:12.413399Z","caller":"traceutil/trace.go:171","msg":"trace[1830558079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"813.84895ms","start":"2026-04-28T13:56:11.599544Z","end":"2026-04-28T13:56:12.413393Z","steps":["trace[1830558079] 'agreement among raft nodes before linearized reading' (duration: 813.815259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:12.41339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.832562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:56:12.413456Z","caller":"traceutil/trace.go:171","msg":"trace[1361034064] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:958; }","duration":"786.933623ms","start":"2026-04-28T13:56:11.626505Z","end":"2026-04-28T13:56:12.413439Z","steps":["trace[1361034064] 'agreement among raft nodes before linearized reading' (duration: 786.733179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:12.413417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:11.599523Z","time spent":"813.889682ms","remote":"127.0.0.1:33884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:56:12.413509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.668569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:12.413537Z","caller":"traceutil/trace.go:171","msg":"trace[1928946300] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:958; }","duration":"491.72558ms","start":"2026-04-28T13:56:11.921799Z","end":"2026-04-28T13:56:12.413524Z","steps":["trace[1928946300] 'agreement among raft nodes before linearized reading' (duration: 491.693999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:12.413558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:11.921777Z","time spent":"491.774462ms","remote":"127.0.0.1:34396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:12.413468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181922266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T13:56:12.413651Z","caller":"traceutil/trace.go:171","msg":"trace[1655056850] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:958; }","duration":"1.1821235s","start":"2026-04-28T13:56:11.231503Z","end":"2026-04-28T13:56:12.413626Z","steps":["trace[1655056850] 'agreement among raft nodes before linearized reading' (duration: 1.181927966s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:12.413733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:11.231445Z","time spent":"1.182257873s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:12.413552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:11.626483Z","time spent":"787.008845ms","remote":"127.0.0.1:34412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:56:33.12977Z","caller":"traceutil/trace.go:171","msg":"trace[752313403] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"212.197858ms","start":"2026-04-28T13:56:32.917552Z","end":"2026-04-28T13:56:33.12975Z","steps":["trace[752313403] 'process raft request' (duration: 212.086946ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:34.190014Z","caller":"traceutil/trace.go:171","msg":"trace[985100055] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"1.267469778s","start":"2026-04-28T13:56:32.922471Z","end":"2026-04-28T13:56:34.189941Z","steps":["trace[985100055] 'process raft request' (duration: 1.267165441s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:34.190317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184221977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.134\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T13:56:34.190507Z","caller":"traceutil/trace.go:171","msg":"trace[768314618] range","detail":"{range_begin:/registry/masterleases/199.19.213.134; range_end:; response_count:1; response_revision:1028; }","duration":"1.1843201s","start":"2026-04-28T13:56:33.00604Z","end":"2026-04-28T13:56:34.19036Z","steps":["trace[768314618] 'agreement among raft nodes before linearized reading' (duration: 1.184124255s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:34.190514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:32.922462Z","time spent":"1.267641842s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:34.190537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:33.006017Z","time spent":"1.184512465s","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.134\" "} {"level":"info","ts":"2026-04-28T13:56:34.190525Z","caller":"traceutil/trace.go:171","msg":"trace[490404636] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1067; }","duration":"1.183743367s","start":"2026-04-28T13:56:33.006101Z","end":"2026-04-28T13:56:34.189844Z","steps":["trace[490404636] 'read index received' (duration: 123.627816ms)","trace[490404636] 'applied index is now lower than readState.Index' (duration: 1.060113931s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:34.194651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.415848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:34.194739Z","caller":"traceutil/trace.go:171","msg":"trace[781875676] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1029; }","duration":"785.51987ms","start":"2026-04-28T13:56:33.4092Z","end":"2026-04-28T13:56:34.194719Z","steps":["trace[781875676] 'agreement among raft nodes before linearized reading' (duration: 785.362916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:34.194743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.579957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:56:34.194784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:33.409151Z","time spent":"785.622752ms","remote":"127.0.0.1:34170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-28T13:56:34.194799Z","caller":"traceutil/trace.go:171","msg":"trace[509739741] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"617.871504ms","start":"2026-04-28T13:56:33.576907Z","end":"2026-04-28T13:56:34.194778Z","steps":["trace[509739741] 'process raft request' (duration: 617.478505ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:34.194811Z","caller":"traceutil/trace.go:171","msg":"trace[325276589] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1029; }","duration":"535.724151ms","start":"2026-04-28T13:56:33.659067Z","end":"2026-04-28T13:56:34.194792Z","steps":["trace[325276589] 'agreement among raft nodes before linearized reading' (duration: 535.582967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:34.194872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:33.659057Z","time spent":"535.802782ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:34.194873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.542313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T13:56:34.194923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:33.576885Z","time spent":"617.965496ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:34.194939Z","caller":"traceutil/trace.go:171","msg":"trace[570842693] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1029; }","duration":"684.647175ms","start":"2026-04-28T13:56:33.510275Z","end":"2026-04-28T13:56:34.194923Z","steps":["trace[570842693] 'agreement among raft nodes before linearized reading' (duration: 684.471712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:34.194985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:33.51022Z","time spent":"684.756089ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:34.195389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.850084ms","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-28T13:56:34.195434Z","caller":"traceutil/trace.go:171","msg":"trace[1723379968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1029; }","duration":"559.921446ms","start":"2026-04-28T13:56:33.635499Z","end":"2026-04-28T13:56:34.195421Z","steps":["trace[1723379968] 'agreement among raft nodes before linearized reading' (duration: 559.192308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:34.195463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:33.635487Z","time spent":"559.967366ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T13:56:34.631908Z","caller":"traceutil/trace.go:171","msg":"trace[1288252495] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"106.019991ms","start":"2026-04-28T13:56:34.525861Z","end":"2026-04-28T13:56:34.631881Z","steps":["trace[1288252495] 'process raft request' (duration: 97.995138ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:35.398953Z","caller":"traceutil/trace.go:171","msg":"trace[1539237534] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"259.421679ms","start":"2026-04-28T13:56:35.139504Z","end":"2026-04-28T13:56:35.398926Z","steps":["trace[1539237534] 'process raft request' (duration: 259.220975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:37.973957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.43983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-28T13:56:37.974001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.246752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:37.974042Z","caller":"traceutil/trace.go:171","msg":"trace[1283099090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"570.576993ms","start":"2026-04-28T13:56:37.403446Z","end":"2026-04-28T13:56:37.974023Z","steps":["trace[1283099090] 'range keys from in-memory index tree' (duration: 569.423136ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:37.974078Z","caller":"traceutil/trace.go:171","msg":"trace[1845314870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1041; }","duration":"375.381465ms","start":"2026-04-28T13:56:37.598676Z","end":"2026-04-28T13:56:37.974057Z","steps":["trace[1845314870] 'range keys from in-memory index tree' (duration: 375.095628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:37.974084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:37.403418Z","time spent":"570.656075ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T13:56:37.974117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:37.598631Z","time spent":"375.476847ms","remote":"127.0.0.1:33884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T13:56:38.348853Z","caller":"traceutil/trace.go:171","msg":"trace[2044387198] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"370.513008ms","start":"2026-04-28T13:56:37.978303Z","end":"2026-04-28T13:56:38.348816Z","steps":["trace[2044387198] 'process raft request' (duration: 370.310393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:38.349063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:37.978288Z","time spent":"370.664012ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:38.504449Z","caller":"traceutil/trace.go:171","msg":"trace[369104892] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1083; }","duration":"177.337669ms","start":"2026-04-28T13:56:38.326988Z","end":"2026-04-28T13:56:38.504326Z","steps":["trace[369104892] 'read index received' (duration: 21.631426ms)","trace[369104892] 'applied index is now lower than readState.Index' (duration: 155.705343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:38.504779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.762439ms","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-28T13:56:38.50485Z","caller":"traceutil/trace.go:171","msg":"trace[1143894301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"177.909142ms","start":"2026-04-28T13:56:38.326931Z","end":"2026-04-28T13:56:38.50484Z","steps":["trace[1143894301] 'agreement among raft nodes before linearized reading' (duration: 177.710298ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:38.504939Z","caller":"traceutil/trace.go:171","msg":"trace[619748278] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"257.002186ms","start":"2026-04-28T13:56:38.247795Z","end":"2026-04-28T13:56:38.504797Z","steps":["trace[619748278] 'process raft request' (duration: 180.308487ms)","trace[619748278] 'compare' (duration: 76.080685ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:53.00323Z","caller":"traceutil/trace.go:171","msg":"trace[1316153499] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1194; }","duration":"394.4012ms","start":"2026-04-28T13:56:52.608802Z","end":"2026-04-28T13:56:53.003203Z","steps":["trace[1316153499] 'read index received' (duration: 394.257256ms)","trace[1316153499] 'applied index is now lower than readState.Index' (duration: 139.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:53.003325Z","caller":"traceutil/trace.go:171","msg":"trace[318340572] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"454.412165ms","start":"2026-04-28T13:56:52.548878Z","end":"2026-04-28T13:56:53.00329Z","steps":["trace[318340572] 'process raft request' (duration: 454.174659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:53.003455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.622526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:56:53.003581Z","caller":"traceutil/trace.go:171","msg":"trace[1955978525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"394.794759ms","start":"2026-04-28T13:56:52.608761Z","end":"2026-04-28T13:56:53.003556Z","steps":["trace[1955978525] 'agreement among raft nodes before linearized reading' (duration: 394.559403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:53.003502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:52.548855Z","time spent":"454.551288ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:53.00361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.747014ms","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-28T13:56:53.003645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:52.608742Z","time spent":"394.892632ms","remote":"127.0.0.1:33872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:56:53.003613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.535453ms","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-28T13:56:53.003649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.071865ms","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-28T13:56:53.00369Z","caller":"traceutil/trace.go:171","msg":"trace[186788043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1148; }","duration":"339.837497ms","start":"2026-04-28T13:56:52.663837Z","end":"2026-04-28T13:56:53.003674Z","steps":["trace[186788043] 'agreement among raft nodes before linearized reading' (duration: 339.600911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:53.003524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.656999ms","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-28T13:56:53.00373Z","caller":"traceutil/trace.go:171","msg":"trace[1710944359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1148; }","duration":"171.195468ms","start":"2026-04-28T13:56:52.832515Z","end":"2026-04-28T13:56:53.003711Z","steps":["trace[1710944359] 'agreement among raft nodes before linearized reading' (duration: 170.929021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:53.003749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:52.663832Z","time spent":"339.898339ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T13:56:53.003762Z","caller":"traceutil/trace.go:171","msg":"trace[199216718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1148; }","duration":"355.945907ms","start":"2026-04-28T13:56:52.647801Z","end":"2026-04-28T13:56:53.003747Z","steps":["trace[199216718] 'agreement among raft nodes before linearized reading' (duration: 355.573838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:53.003797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:52.647791Z","time spent":"355.999168ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T13:56:53.003766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.279908ms","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-28T13:56:53.004016Z","caller":"traceutil/trace.go:171","msg":"trace[2096165094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1148; }","duration":"340.584234ms","start":"2026-04-28T13:56:52.663407Z","end":"2026-04-28T13:56:53.003992Z","steps":["trace[2096165094] 'agreement among raft nodes before linearized reading' (duration: 340.126023ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:53.003704Z","caller":"traceutil/trace.go:171","msg":"trace[1846981365] 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:1148; }","duration":"320.76099ms","start":"2026-04-28T13:56:52.682923Z","end":"2026-04-28T13:56:53.003684Z","steps":["trace[1846981365] 'agreement among raft nodes before linearized reading' (duration: 320.490852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:53.004176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:52.6829Z","time spent":"321.2529ms","remote":"127.0.0.1:40072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:53.004296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:52.663343Z","time spent":"340.715477ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T13:56:54.922542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686706136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:56:55.423505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686706136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:56:55.999335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686706136,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T13:56:56.278156Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.86695951s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T13:56:56.278653Z","caller":"traceutil/trace.go:171","msg":"trace[1486206659] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"1.867551415s","start":"2026-04-28T13:56:54.41107Z","end":"2026-04-28T13:56:56.278621Z","steps":["trace[1486206659] 'process raft request' (duration: 1.86736155s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:56.278847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:54.411041Z","time spent":"1.867712909s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:56:56.464912Z","caller":"traceutil/trace.go:171","msg":"trace[1145500320] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1201; }","duration":"2.043313437s","start":"2026-04-28T13:56:54.421578Z","end":"2026-04-28T13:56:56.464891Z","steps":["trace[1145500320] 'read index received' (duration: 1.856823778s)","trace[1145500320] 'applied index is now lower than readState.Index' (duration: 186.488149ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:56:56.464968Z","caller":"traceutil/trace.go:171","msg":"trace[2077930525] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"1.455682307s","start":"2026-04-28T13:56:55.009247Z","end":"2026-04-28T13:56:56.464929Z","steps":["trace[2077930525] 'process raft request' (duration: 1.399225542s)","trace[2077930525] 'compare' (duration: 56.140678ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:56:56.465067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.865140419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:56:56.46513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.043540682s","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-28T13:56:56.465135Z","caller":"traceutil/trace.go:171","msg":"trace[571808606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"1.865262172s","start":"2026-04-28T13:56:54.599855Z","end":"2026-04-28T13:56:56.465117Z","steps":["trace[571808606] 'agreement among raft nodes before linearized reading' (duration: 1.86516113s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:56.465151Z","caller":"traceutil/trace.go:171","msg":"trace[697412421] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1155; }","duration":"2.043613824s","start":"2026-04-28T13:56:54.421532Z","end":"2026-04-28T13:56:56.465146Z","steps":["trace[697412421] 'agreement among raft nodes before linearized reading' (duration: 2.0434673s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:56.465132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:55.009225Z","time spent":"1.455818629s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:56:56.465177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:54.421513Z","time spent":"2.043657254s","remote":"127.0.0.1:34148","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-28T13:56:56.465177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:54.599828Z","time spent":"1.865340514s","remote":"127.0.0.1:33872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:56:56.465248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44536876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T13:56:56.465276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445542674s","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-28T13:56:56.465346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.743606ms","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:7"} {"level":"info","ts":"2026-04-28T13:56:56.465365Z","caller":"traceutil/trace.go:171","msg":"trace[2021966474] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1155; }","duration":"224.805488ms","start":"2026-04-28T13:56:56.240554Z","end":"2026-04-28T13:56:56.465359Z","steps":["trace[2021966474] 'agreement among raft nodes before linearized reading' (duration: 224.776287ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:56.465356Z","caller":"traceutil/trace.go:171","msg":"trace[1285953205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1155; }","duration":"1.445544894s","start":"2026-04-28T13:56:55.019785Z","end":"2026-04-28T13:56:56.46533Z","steps":["trace[1285953205] 'agreement among raft nodes before linearized reading' (duration: 1.445283558s)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:56:56.465368Z","caller":"traceutil/trace.go:171","msg":"trace[1835583008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1155; }","duration":"1.445658576s","start":"2026-04-28T13:56:55.019687Z","end":"2026-04-28T13:56:56.465345Z","steps":["trace[1835583008] 'agreement among raft nodes before linearized reading' (duration: 1.445355209s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:56.465384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.514724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T13:56:56.465416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:55.019761Z","time spent":"1.445641597s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T13:56:56.465435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:55.01968Z","time spent":"1.445743728s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T13:56:56.465468Z","caller":"traceutil/trace.go:171","msg":"trace[759081087] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1155; }","duration":"385.612766ms","start":"2026-04-28T13:56:56.079804Z","end":"2026-04-28T13:56:56.465417Z","steps":["trace[759081087] 'agreement among raft nodes before linearized reading' (duration: 385.532414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:56.465248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445287158s","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-28T13:56:56.465524Z","caller":"traceutil/trace.go:171","msg":"trace[2031838655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1155; }","duration":"1.445651976s","start":"2026-04-28T13:56:55.019861Z","end":"2026-04-28T13:56:56.465513Z","steps":["trace[2031838655] 'agreement among raft nodes before linearized reading' (duration: 1.445159245s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:56.465527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:56.079749Z","time spent":"385.761489ms","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T13:56:56.465568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:55.019821Z","time spent":"1.445732838s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T13:56:56.465297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449550045s","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-28T13:56:56.465644Z","caller":"traceutil/trace.go:171","msg":"trace[226617922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1155; }","duration":"1.449898213s","start":"2026-04-28T13:56:55.015717Z","end":"2026-04-28T13:56:56.465616Z","steps":["trace[226617922] 'agreement among raft nodes before linearized reading' (duration: 1.449547365s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:56:56.465666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:56:55.015709Z","time spent":"1.449950544s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T13:57:13.002887Z","caller":"traceutil/trace.go:171","msg":"trace[1696878722] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"468.111066ms","start":"2026-04-28T13:57:12.53475Z","end":"2026-04-28T13:57:13.002861Z","steps":["trace[1696878722] 'read index received' (duration: 466.931009ms)","trace[1696878722] 'applied index is now lower than readState.Index' (duration: 1.179087ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:13.00328Z","caller":"traceutil/trace.go:171","msg":"trace[806084839] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"486.444093ms","start":"2026-04-28T13:57:12.516821Z","end":"2026-04-28T13:57:13.003265Z","steps":["trace[806084839] 'process raft request' (duration: 484.991129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:13.0034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:12.516784Z","time spent":"486.536085ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:57:13.003761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.020006ms","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-28T13:57:13.003813Z","caller":"traceutil/trace.go:171","msg":"trace[761054568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"469.130459ms","start":"2026-04-28T13:57:12.534671Z","end":"2026-04-28T13:57:13.003801Z","steps":["trace[761054568] 'agreement among raft nodes before linearized reading' (duration: 468.969185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:13.003853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:12.534643Z","time spent":"469.20347ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T13:57:13.004266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.490908ms","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-28T13:57:13.004319Z","caller":"traceutil/trace.go:171","msg":"trace[1884170635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"455.546059ms","start":"2026-04-28T13:57:12.54876Z","end":"2026-04-28T13:57:13.004306Z","steps":["trace[1884170635] 'agreement among raft nodes before linearized reading' (duration: 455.412456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:13.004485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:12.548745Z","time spent":"455.729443ms","remote":"127.0.0.1:34148","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-28T13:57:13.004617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.609506ms","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-28T13:57:13.004646Z","caller":"traceutil/trace.go:171","msg":"trace[10167612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1227; }","duration":"381.677038ms","start":"2026-04-28T13:57:12.622961Z","end":"2026-04-28T13:57:13.004638Z","steps":["trace[10167612] 'agreement among raft nodes before linearized reading' (duration: 381.603986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:13.00467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:12.622938Z","time spent":"381.727338ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T13:57:13.004765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.290372ms","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-28T13:57:13.004787Z","caller":"traceutil/trace.go:171","msg":"trace[1886646448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"382.340812ms","start":"2026-04-28T13:57:12.622441Z","end":"2026-04-28T13:57:13.004782Z","steps":["trace[1886646448] 'agreement among raft nodes before linearized reading' (duration: 382.277151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:13.004808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:12.622428Z","time spent":"382.376013ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T13:57:13.004903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.605392ms","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-28T13:57:13.004924Z","caller":"traceutil/trace.go:171","msg":"trace[255116122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"383.667063ms","start":"2026-04-28T13:57:12.621252Z","end":"2026-04-28T13:57:13.004919Z","steps":["trace[255116122] 'agreement among raft nodes before linearized reading' (duration: 383.614022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:13.004944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:12.621241Z","time spent":"383.699314ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T13:57:13.005093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.575429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:57:13.005116Z","caller":"traceutil/trace.go:171","msg":"trace[3012675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"404.628619ms","start":"2026-04-28T13:57:12.600482Z","end":"2026-04-28T13:57:13.00511Z","steps":["trace[3012675] 'agreement among raft nodes before linearized reading' (duration: 404.595569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:13.005133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:12.600464Z","time spent":"404.664821ms","remote":"127.0.0.1:33884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T13:57:13.11141Z","caller":"traceutil/trace.go:171","msg":"trace[1300806079] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"102.202336ms","start":"2026-04-28T13:57:13.009181Z","end":"2026-04-28T13:57:13.111383Z","steps":["trace[1300806079] 'read index received' (duration: 61.106681ms)","trace[1300806079] 'applied index is now lower than readState.Index' (duration: 41.094405ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:13.111474Z","caller":"traceutil/trace.go:171","msg":"trace[440367472] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"102.765789ms","start":"2026-04-28T13:57:13.00867Z","end":"2026-04-28T13:57:13.111436Z","steps":["trace[440367472] 'process raft request' (duration: 61.678584ms)","trace[440367472] 'compare' (duration: 40.807598ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:13.111579Z","caller":"traceutil/trace.go:171","msg":"trace[2120574186] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"101.212094ms","start":"2026-04-28T13:57:13.010352Z","end":"2026-04-28T13:57:13.111564Z","steps":["trace[2120574186] 'process raft request' (duration: 100.982348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:13.111731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.524913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.134\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T13:57:13.111786Z","caller":"traceutil/trace.go:171","msg":"trace[1360166088] range","detail":"{range_begin:/registry/masterleases/199.19.213.134; range_end:; response_count:1; response_revision:1229; }","duration":"102.618685ms","start":"2026-04-28T13:57:13.009152Z","end":"2026-04-28T13:57:13.111771Z","steps":["trace[1360166088] 'agreement among raft nodes before linearized reading' (duration: 102.338039ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:13.111781Z","caller":"traceutil/trace.go:171","msg":"trace[1115255432] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"100.616879ms","start":"2026-04-28T13:57:13.011149Z","end":"2026-04-28T13:57:13.111765Z","steps":["trace[1115255432] 'process raft request' (duration: 100.378324ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:13.248491Z","caller":"traceutil/trace.go:171","msg":"trace[110622631] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"109.82074ms","start":"2026-04-28T13:57:13.13864Z","end":"2026-04-28T13:57:13.248461Z","steps":["trace[110622631] 'process raft request' (duration: 97.762005ms)","trace[110622631] 'compare' (duration: 11.886801ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:14.098051Z","caller":"traceutil/trace.go:171","msg":"trace[1366789068] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"260.369856ms","start":"2026-04-28T13:57:13.837651Z","end":"2026-04-28T13:57:14.098021Z","steps":["trace[1366789068] 'process raft request' (duration: 260.204352ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:14.661898Z","caller":"traceutil/trace.go:171","msg":"trace[1890274617] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"559.265841ms","start":"2026-04-28T13:57:14.102613Z","end":"2026-04-28T13:57:14.661879Z","steps":["trace[1890274617] 'process raft request' (duration: 559.084797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:14.662002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:14.102588Z","time spent":"559.363853ms","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":852,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:57:15.244694Z","caller":"traceutil/trace.go:171","msg":"trace[57986528] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"129.663641ms","start":"2026-04-28T13:57:15.114995Z","end":"2026-04-28T13:57:15.244659Z","steps":["trace[57986528] 'read index received' (duration: 81.431543ms)","trace[57986528] 'applied index is now lower than readState.Index' (duration: 48.230508ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:15.244843Z","caller":"traceutil/trace.go:171","msg":"trace[1916136642] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"233.809262ms","start":"2026-04-28T13:57:15.01102Z","end":"2026-04-28T13:57:15.24483Z","steps":["trace[1916136642] 'process raft request' (duration: 185.504252ms)","trace[1916136642] 'compare' (duration: 47.503302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:57:15.244952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.489967ms","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-28T13:57:15.245041Z","caller":"traceutil/trace.go:171","msg":"trace[1098676025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"129.62446ms","start":"2026-04-28T13:57:15.115398Z","end":"2026-04-28T13:57:15.245022Z","steps":["trace[1098676025] 'agreement among raft nodes before linearized reading' (duration: 129.442026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:15.245298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.244912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-28T13:57:15.245314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.204118ms","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-28T13:57:15.245366Z","caller":"traceutil/trace.go:171","msg":"trace[1358290094] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"128.288521ms","start":"2026-04-28T13:57:15.117065Z","end":"2026-04-28T13:57:15.245353Z","steps":["trace[1358290094] 'agreement among raft nodes before linearized reading' (duration: 128.097376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:15.245394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.59987ms","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-28T13:57:15.245422Z","caller":"traceutil/trace.go:171","msg":"trace[431334925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"129.643881ms","start":"2026-04-28T13:57:15.115771Z","end":"2026-04-28T13:57:15.245415Z","steps":["trace[431334925] 'agreement among raft nodes before linearized reading' (duration: 129.57692ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:15.24533Z","caller":"traceutil/trace.go:171","msg":"trace[581256589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"129.293833ms","start":"2026-04-28T13:57:15.116027Z","end":"2026-04-28T13:57:15.245321Z","steps":["trace[581256589] 'agreement among raft nodes before linearized reading' (duration: 129.208901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:15.245663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.667503ms","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-28T13:57:15.245698Z","caller":"traceutil/trace.go:171","msg":"trace[2140919684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"130.778406ms","start":"2026-04-28T13:57:15.114909Z","end":"2026-04-28T13:57:15.245687Z","steps":["trace[2140919684] 'agreement among raft nodes before linearized reading' (duration: 130.695554ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:15.815559Z","caller":"traceutil/trace.go:171","msg":"trace[576313101] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"116.663256ms","start":"2026-04-28T13:57:15.698848Z","end":"2026-04-28T13:57:15.815512Z","steps":["trace[576313101] 'process raft request' (duration: 48.005453ms)","trace[576313101] 'compare' (duration: 68.408878ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:15.815892Z","caller":"traceutil/trace.go:171","msg":"trace[462203342] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"114.879665ms","start":"2026-04-28T13:57:15.700981Z","end":"2026-04-28T13:57:15.81586Z","steps":["trace[462203342] 'process raft request' (duration: 114.471876ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:15.816031Z","caller":"traceutil/trace.go:171","msg":"trace[838857631] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"114.023366ms","start":"2026-04-28T13:57:15.701988Z","end":"2026-04-28T13:57:15.816011Z","steps":["trace[838857631] 'process raft request' (duration: 113.530945ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:16.03004Z","caller":"traceutil/trace.go:171","msg":"trace[953714800] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"202.98362ms","start":"2026-04-28T13:57:15.826699Z","end":"2026-04-28T13:57:16.029683Z","steps":["trace[953714800] 'process raft request' (duration: 202.52797ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:16.030036Z","caller":"traceutil/trace.go:171","msg":"trace[81058073] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"109.744718ms","start":"2026-04-28T13:57:15.919684Z","end":"2026-04-28T13:57:16.029429Z","steps":["trace[81058073] 'read index received' (duration: 108.537791ms)","trace[81058073] 'applied index is now lower than readState.Index' (duration: 1.206047ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:16.03004Z","caller":"traceutil/trace.go:171","msg":"trace[474814457] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"202.653102ms","start":"2026-04-28T13:57:15.827322Z","end":"2026-04-28T13:57:16.029975Z","steps":["trace[474814457] 'process raft request' (duration: 202.047829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:16.030653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.938865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-28T13:57:16.030715Z","caller":"traceutil/trace.go:171","msg":"trace[943791800] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1253; }","duration":"111.061168ms","start":"2026-04-28T13:57:15.919637Z","end":"2026-04-28T13:57:16.030698Z","steps":["trace[943791800] 'agreement among raft nodes before linearized reading' (duration: 110.509755ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:16.208746Z","caller":"traceutil/trace.go:171","msg":"trace[767337307] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"287.049443ms","start":"2026-04-28T13:57:15.921674Z","end":"2026-04-28T13:57:16.208724Z","steps":["trace[767337307] 'process raft request' (duration: 232.03091ms)","trace[767337307] 'compare' (duration: 54.86536ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:16.215926Z","caller":"traceutil/trace.go:171","msg":"trace[878012950] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"181.214135ms","start":"2026-04-28T13:57:16.034702Z","end":"2026-04-28T13:57:16.215917Z","steps":["trace[878012950] 'process raft request' (duration: 181.142114ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:16.471163Z","caller":"traceutil/trace.go:171","msg":"trace[1434588367] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"167.3342ms","start":"2026-04-28T13:57:16.30381Z","end":"2026-04-28T13:57:16.471144Z","steps":["trace[1434588367] 'process raft request' (duration: 94.982663ms)","trace[1434588367] 'compare' (duration: 72.265235ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:16.685488Z","caller":"traceutil/trace.go:171","msg":"trace[1903145832] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"127.953793ms","start":"2026-04-28T13:57:16.557506Z","end":"2026-04-28T13:57:16.685459Z","steps":["trace[1903145832] 'process raft request' (duration: 127.809039ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:16.69126Z","caller":"traceutil/trace.go:171","msg":"trace[973619743] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1308; }","duration":"106.197837ms","start":"2026-04-28T13:57:16.585051Z","end":"2026-04-28T13:57:16.691249Z","steps":["trace[973619743] 'read index received' (duration: 100.235412ms)","trace[973619743] 'applied index is now lower than readState.Index' (duration: 5.961695ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:16.69146Z","caller":"traceutil/trace.go:171","msg":"trace[1646802881] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"129.821865ms","start":"2026-04-28T13:57:16.561624Z","end":"2026-04-28T13:57:16.691446Z","steps":["trace[1646802881] 'process raft request' (duration: 129.515538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:16.691784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.561725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:57:16.691905Z","caller":"traceutil/trace.go:171","msg":"trace[1911877083] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1258; }","duration":"106.787501ms","start":"2026-04-28T13:57:16.58502Z","end":"2026-04-28T13:57:16.691807Z","steps":["trace[1911877083] 'agreement among raft nodes before linearized reading' (duration: 106.571826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:17.299059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.524452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:57:17.299167Z","caller":"traceutil/trace.go:171","msg":"trace[1191753171] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"609.173165ms","start":"2026-04-28T13:57:16.689967Z","end":"2026-04-28T13:57:17.29914Z","steps":["trace[1191753171] 'process raft request' (duration: 492.50766ms)","trace[1191753171] 'compare' (duration: 116.136484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:57:17.299251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:16.68995Z","time spent":"609.264068ms","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":858,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T13:57:17.299268Z","caller":"traceutil/trace.go:171","msg":"trace[275854940] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"604.312885ms","start":"2026-04-28T13:57:16.694943Z","end":"2026-04-28T13:57:17.299256Z","steps":["trace[275854940] 'process raft request' (duration: 604.202742ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:17.299267Z","caller":"traceutil/trace.go:171","msg":"trace[596669360] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"607.947939ms","start":"2026-04-28T13:57:16.691292Z","end":"2026-04-28T13:57:17.29924Z","steps":["trace[596669360] 'read index received' (duration: 491.193882ms)","trace[596669360] 'applied index is now lower than readState.Index' (duration: 116.751217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:57:17.299321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:16.694935Z","time spent":"604.359536ms","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:57:17.299454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.020776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:57:17.299517Z","caller":"traceutil/trace.go:171","msg":"trace[1335420384] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1260; }","duration":"679.091387ms","start":"2026-04-28T13:57:16.620406Z","end":"2026-04-28T13:57:17.299497Z","steps":["trace[1335420384] 'agreement among raft nodes before linearized reading' (duration: 678.965565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:17.29957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:16.620313Z","time spent":"679.243961ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T13:57:17.314241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.638658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:57:17.314431Z","caller":"traceutil/trace.go:171","msg":"trace[1529899830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"381.856153ms","start":"2026-04-28T13:57:16.932563Z","end":"2026-04-28T13:57:17.314419Z","steps":["trace[1529899830] 'agreement among raft nodes before linearized reading' (duration: 381.644298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:17.314495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:16.932546Z","time spent":"381.940904ms","remote":"127.0.0.1:33884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T13:57:17.837282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.894297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T13:57:17.837459Z","caller":"traceutil/trace.go:171","msg":"trace[620836606] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"449.031241ms","start":"2026-04-28T13:57:17.388407Z","end":"2026-04-28T13:57:17.837438Z","steps":["trace[620836606] 'process raft request' (duration: 303.901828ms)","trace[620836606] 'compare' (duration: 144.796515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T13:57:17.837531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:17.388389Z","time spent":"449.110702ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T13:57:17.837712Z","caller":"traceutil/trace.go:171","msg":"trace[1772391480] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1320; }","duration":"420.082701ms","start":"2026-04-28T13:57:17.417598Z","end":"2026-04-28T13:57:17.837681Z","steps":["trace[1772391480] 'read index received' (duration: 275.630544ms)","trace[1772391480] 'applied index is now lower than readState.Index' (duration: 144.450667ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T13:57:17.838015Z","caller":"traceutil/trace.go:171","msg":"trace[1514622719] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"449.550923ms","start":"2026-04-28T13:57:17.388446Z","end":"2026-04-28T13:57:17.837997Z","steps":["trace[1514622719] 'process raft request' (duration: 448.939719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:17.838168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:17.388435Z","time spent":"449.635215ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T13:57:17.839207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.751028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-28T13:57:17.839529Z","caller":"traceutil/trace.go:171","msg":"trace[1765945240] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1270; }","duration":"454.086045ms","start":"2026-04-28T13:57:17.385422Z","end":"2026-04-28T13:57:17.839508Z","steps":["trace[1765945240] 'agreement among raft nodes before linearized reading' (duration: 452.748905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:17.839651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:17.38539Z","time spent":"454.245069ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T13:57:17.841279Z","caller":"traceutil/trace.go:171","msg":"trace[1241433828] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"418.65057ms","start":"2026-04-28T13:57:17.422616Z","end":"2026-04-28T13:57:17.841267Z","steps":["trace[1241433828] 'process raft request' (duration: 418.428235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:17.841334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:17.422596Z","time spent":"418.712952ms","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":792,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T13:57:17.841331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.419307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-28T13:57:17.841353Z","caller":"traceutil/trace.go:171","msg":"trace[755979697] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"167.339309ms","start":"2026-04-28T13:57:17.673985Z","end":"2026-04-28T13:57:17.841324Z","steps":["trace[755979697] 'process raft request' (duration: 167.151735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:17.841563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.280242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T13:57:17.841607Z","caller":"traceutil/trace.go:171","msg":"trace[1883397560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"241.376044ms","start":"2026-04-28T13:57:17.60022Z","end":"2026-04-28T13:57:17.841596Z","steps":["trace[1883397560] 'agreement among raft nodes before linearized reading' (duration: 241.294212ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T13:57:17.841403Z","caller":"traceutil/trace.go:171","msg":"trace[2112148723] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1272; }","duration":"419.517079ms","start":"2026-04-28T13:57:17.421876Z","end":"2026-04-28T13:57:17.841393Z","steps":["trace[2112148723] 'agreement among raft nodes before linearized reading' (duration: 419.326485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:17.842012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:17.421864Z","time spent":"420.130773ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-28T13:57:23.457586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.435925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.134\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T13:57:23.457748Z","caller":"traceutil/trace.go:171","msg":"trace[1823843844] range","detail":"{range_begin:/registry/masterleases/199.19.213.134; range_end:; response_count:1; response_revision:1303; }","duration":"447.595499ms","start":"2026-04-28T13:57:23.010123Z","end":"2026-04-28T13:57:23.457719Z","steps":["trace[1823843844] 'range keys from in-memory index tree' (duration: 447.300091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:23.457815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T13:57:23.010078Z","time spent":"447.724161ms","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.134\" "} {"level":"info","ts":"2026-04-28T13:57:23.472966Z","caller":"traceutil/trace.go:171","msg":"trace[1354310467] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"137.460099ms","start":"2026-04-28T13:57:23.335477Z","end":"2026-04-28T13:57:23.472937Z","steps":["trace[1354310467] 'process raft request' (duration: 137.338396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:23.544913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.736667ms","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-28T13:57:23.544987Z","caller":"traceutil/trace.go:171","msg":"trace[953010829] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"105.861719ms","start":"2026-04-28T13:57:23.439109Z","end":"2026-04-28T13:57:23.544971Z","steps":["trace[953010829] 'agreement among raft nodes before linearized reading' (duration: 105.676955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T13:57:23.545115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.025648ms","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-28T13:57:23.545205Z","caller":"traceutil/trace.go:171","msg":"trace[642735544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"104.17348ms","start":"2026-04-28T13:57:23.441009Z","end":"2026-04-28T13:57:23.545183Z","steps":["trace[642735544] 'agreement among raft nodes before linearized reading' (duration: 103.875774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:00:34.11886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.465532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-04-28T14:00:34.118957Z","caller":"traceutil/trace.go:171","msg":"trace[1229325273] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2293; }","duration":"204.610865ms","start":"2026-04-28T14:00:33.914325Z","end":"2026-04-28T14:00:34.118936Z","steps":["trace[1229325273] 'range keys from in-memory index tree' (duration: 204.258565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:11.018084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:11.518583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:12.019662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:12.600103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:13.100229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:13.600246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000509668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-28T14:01:13.600369Z","caller":"traceutil/trace.go:171","msg":"trace[1302831548] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000637711s","start":"2026-04-28T14:01:11.599677Z","end":"2026-04-28T14:01:13.600315Z","steps":["trace[1302831548] 'agreement among raft nodes before linearized reading' (duration: 2.000526238s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:13.600418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:11.599667Z","time spent":"2.000739423s","remote":"127.0.0.1:33884","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-28T14:01:13.600782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:14.10158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:14.602544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:15.103171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:15.504713Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.600640509s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T14:01:15.505105Z","caller":"traceutil/trace.go:171","msg":"trace[409780983] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"4.994127764s","start":"2026-04-28T14:01:10.510953Z","end":"2026-04-28T14:01:15.505081Z","steps":["trace[409780983] 'process raft request' (duration: 4.99396085s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.505273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:10.510937Z","time spent":"4.994256967s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:01:15.602053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000362875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T14:01:15.602163Z","caller":"traceutil/trace.go:171","msg":"trace[36635170] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000526108s","start":"2026-04-28T14:01:13.601613Z","end":"2026-04-28T14:01:15.602139Z","steps":["trace[36635170] 'agreement among raft nodes before linearized reading' (duration: 2.000390225s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.602265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:13.601603Z","time spent":"2.000646791s","remote":"127.0.0.1:33872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/28 14:01:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T14:01:15.809666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999837832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T14:01:15.80976Z","caller":"traceutil/trace.go:171","msg":"trace[2095394284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999991496s","start":"2026-04-28T14:01:10.809745Z","end":"2026-04-28T14:01:15.809736Z","steps":["trace[2095394284] 'agreement among raft nodes before linearized reading' (duration: 4.999865833s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.809823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:10.809725Z","time spent":"5.000083988s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/28 14:01:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T14:01:15.821006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.606019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T14:01:15.821107Z","caller":"traceutil/trace.go:171","msg":"trace[649197960] transaction","detail":"{read_only:false; response_revision:2489; number_of_response:1; }","duration":"4.407162565s","start":"2026-04-28T14:01:11.413933Z","end":"2026-04-28T14:01:15.821095Z","steps":["trace[649197960] 'process raft request' (duration: 4.228416792s)","trace[649197960] 'compare' (duration: 178.540288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:01:15.821157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:11.413905Z","time spent":"4.407228566s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:01:15.821441Z","caller":"traceutil/trace.go:171","msg":"trace[1840993784] transaction","detail":"{read_only:false; response_revision:2490; number_of_response:1; }","duration":"3.944038153s","start":"2026-04-28T14:01:11.877391Z","end":"2026-04-28T14:01:15.821429Z","steps":["trace[1840993784] 'process raft request' (duration: 3.943665005s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.821511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:11.877368Z","time spent":"3.944102105s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:01:15.821533Z","caller":"traceutil/trace.go:171","msg":"trace[436316881] linearizableReadLoop","detail":"{readStateIndex:2591; appliedIndex:2587; }","duration":"5.303486326s","start":"2026-04-28T14:01:10.518021Z","end":"2026-04-28T14:01:15.821507Z","steps":["trace[436316881] 'read index received' (duration: 4.986956403s)","trace[436316881] 'applied index is now lower than readState.Index' (duration: 316.528963ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T14:01:15.821775Z","caller":"traceutil/trace.go:171","msg":"trace[1112520425] transaction","detail":"{read_only:false; response_revision:2492; number_of_response:1; }","duration":"216.114813ms","start":"2026-04-28T14:01:15.605651Z","end":"2026-04-28T14:01:15.821766Z","steps":["trace[1112520425] 'process raft request' (duration: 215.823727ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:15.821833Z","caller":"traceutil/trace.go:171","msg":"trace[323194430] transaction","detail":"{read_only:false; response_revision:2491; number_of_response:1; }","duration":"3.420078923s","start":"2026-04-28T14:01:12.401741Z","end":"2026-04-28T14:01:15.82182Z","steps":["trace[323194430] 'process raft request' (duration: 3.419477429s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.82193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:12.401717Z","time spent":"3.420148424s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:01:15.821952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.75946562s","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-28T14:01:15.821978Z","caller":"traceutil/trace.go:171","msg":"trace[1697454444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2492; }","duration":"3.759522921s","start":"2026-04-28T14:01:12.062449Z","end":"2026-04-28T14:01:15.821972Z","steps":["trace[1697454444] 'agreement among raft nodes before linearized reading' (duration: 3.759413959s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:12.062432Z","time spent":"3.759566603s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T14:01:15.822024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.759560272s","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-28T14:01:15.821924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.25706466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-28T14:01:15.822056Z","caller":"traceutil/trace.go:171","msg":"trace[1306508768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2492; }","duration":"3.759586643s","start":"2026-04-28T14:01:12.062458Z","end":"2026-04-28T14:01:15.822044Z","steps":["trace[1306508768] 'agreement among raft nodes before linearized reading' (duration: 3.759502421s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.574481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T14:01:15.822073Z","caller":"traceutil/trace.go:171","msg":"trace[774628609] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:2492; }","duration":"5.257259104s","start":"2026-04-28T14:01:10.564803Z","end":"2026-04-28T14:01:15.822062Z","steps":["trace[774628609] 'agreement among raft nodes before linearized reading' (duration: 5.257041749s)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:15.822117Z","caller":"traceutil/trace.go:171","msg":"trace[1032326174] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2492; }","duration":"399.604482ms","start":"2026-04-28T14:01:15.422484Z","end":"2026-04-28T14:01:15.822089Z","steps":["trace[1032326174] 'agreement among raft nodes before linearized reading' (duration: 399.475089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.075969102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-04-28T14:01:15.822142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:10.564787Z","time spent":"5.257345237s","remote":"127.0.0.1:34102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":544,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-04-28T14:01:15.822161Z","caller":"traceutil/trace.go:171","msg":"trace[1206950539] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2492; }","duration":"4.076005303s","start":"2026-04-28T14:01:11.746149Z","end":"2026-04-28T14:01:15.822155Z","steps":["trace[1206950539] 'agreement among raft nodes before linearized reading' (duration: 4.075943672s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:11.746139Z","time spent":"4.076033173s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T14:01:15.822176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.969739465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T14:01:15.8222Z","caller":"traceutil/trace.go:171","msg":"trace[1185864022] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2492; }","duration":"1.969802307s","start":"2026-04-28T14:01:13.852391Z","end":"2026-04-28T14:01:15.822194Z","steps":["trace[1185864022] 'agreement among raft nodes before linearized reading' (duration: 1.969752056s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:13.852369Z","time spent":"1.969850707s","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":18,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-28T14:01:15.822082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:12.062405Z","time spent":"3.759672934s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T14:01:15.822312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.276911103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T14:01:15.822327Z","caller":"traceutil/trace.go:171","msg":"trace[3906297] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2492; }","duration":"4.276971485s","start":"2026-04-28T14:01:11.545351Z","end":"2026-04-28T14:01:15.822322Z","steps":["trace[3906297] 'agreement among raft nodes before linearized reading' (duration: 4.276927744s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:11.545331Z","time spent":"4.277009605s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T14:01:15.822351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.296770064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-28T14:01:15.822379Z","caller":"traceutil/trace.go:171","msg":"trace[422316555] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2492; }","duration":"2.296836456s","start":"2026-04-28T14:01:13.525535Z","end":"2026-04-28T14:01:15.822371Z","steps":["trace[422316555] 'agreement among raft nodes before linearized reading' (duration: 2.296771054s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.8224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:13.525511Z","time spent":"2.296885337s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-28T14:01:15.822441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.915275538s","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-28T14:01:15.822458Z","caller":"traceutil/trace.go:171","msg":"trace[624137879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2492; }","duration":"4.91530069s","start":"2026-04-28T14:01:10.907153Z","end":"2026-04-28T14:01:15.822454Z","steps":["trace[624137879] 'agreement among raft nodes before linearized reading' (duration: 4.915262709s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:10.907148Z","time spent":"4.91532066s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T14:01:15.822507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.304481928s","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:119 size:91446"} {"level":"warn","ts":"2026-04-28T14:01:15.822538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.799369934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.134\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-28T14:01:15.822555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.925148582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"warn","ts":"2026-04-28T14:01:15.822569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.76525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T14:01:15.822576Z","caller":"traceutil/trace.go:171","msg":"trace[1881227384] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2492; }","duration":"4.925189782s","start":"2026-04-28T14:01:10.897382Z","end":"2026-04-28T14:01:15.822572Z","steps":["trace[1881227384] 'agreement among raft nodes before linearized reading' (duration: 4.925141081s)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:15.822587Z","caller":"traceutil/trace.go:171","msg":"trace[376829926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2492; }","duration":"213.822862ms","start":"2026-04-28T14:01:15.608759Z","end":"2026-04-28T14:01:15.822582Z","steps":["trace[376829926] 'agreement among raft nodes before linearized reading' (duration: 213.792041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.409237322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-04-28T14:01:15.822142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:15.422421Z","time spent":"399.713464ms","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":153,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-28T14:01:15.822618Z","caller":"traceutil/trace.go:171","msg":"trace[1370514201] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2492; }","duration":"4.409482517s","start":"2026-04-28T14:01:11.413128Z","end":"2026-04-28T14:01:15.822611Z","steps":["trace[1370514201] 'agreement among raft nodes before linearized reading' (duration: 4.409250892s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:11.41311Z","time spent":"4.409530448s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-28T14:01:15.82254Z","caller":"traceutil/trace.go:171","msg":"trace[1568659606] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:119; response_revision:2492; }","duration":"5.304532038s","start":"2026-04-28T14:01:10.518001Z","end":"2026-04-28T14:01:15.822533Z","steps":["trace[1568659606] 'agreement among raft nodes before linearized reading' (duration: 5.30415285s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:10.897334Z","time spent":"4.925254424s","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":645,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-28T14:01:15.822558Z","caller":"traceutil/trace.go:171","msg":"trace[1581633263] range","detail":"{range_begin:/registry/masterleases/199.19.213.134; range_end:; response_count:1; response_revision:2492; }","duration":"2.799419636s","start":"2026-04-28T14:01:13.023133Z","end":"2026-04-28T14:01:15.822553Z","steps":["trace[1581633263] 'agreement among raft nodes before linearized reading' (duration: 2.799360305s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:15.822826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:13.023118Z","time spent":"2.799700733s","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.134\" "} {"level":"warn","ts":"2026-04-28T14:01:15.822717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:10.517992Z","time spent":"5.304713982s","remote":"127.0.0.1:33956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":119,"response size":91470,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-04-28T14:01:22.027484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:22.52861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:23.029641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:23.529828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:23.598089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999887952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-28T14:01:23.598174Z","caller":"traceutil/trace.go:171","msg":"trace[434277942] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000062776s","start":"2026-04-28T14:01:21.598091Z","end":"2026-04-28T14:01:23.598153Z","steps":["trace[434277942] 'agreement among raft nodes before linearized reading' (duration: 1.999923583s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:23.598226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:21.598068Z","time spent":"2.000146608s","remote":"127.0.0.1:33884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/28 14:01:23 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-28T14:01:24.033396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726541355686710588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T14:01:24.418124Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.498307018s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T14:01:24.418542Z","caller":"traceutil/trace.go:171","msg":"trace[876491470] transaction","detail":"{read_only:false; response_revision:2519; number_of_response:1; }","duration":"3.498915461s","start":"2026-04-28T14:01:20.9196Z","end":"2026-04-28T14:01:24.418515Z","steps":["trace[876491470] 'process raft request' (duration: 3.498747507s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.418715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:20.919576Z","time spent":"3.499052095s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:01:24.432027Z","caller":"traceutil/trace.go:171","msg":"trace[187271061] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"3.509165103s","start":"2026-04-28T14:01:20.922828Z","end":"2026-04-28T14:01:24.431993Z","steps":["trace[187271061] 'process raft request' (duration: 3.50906645s)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:24.432082Z","caller":"traceutil/trace.go:171","msg":"trace[314708354] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"3.512336113s","start":"2026-04-28T14:01:20.919697Z","end":"2026-04-28T14:01:24.432034Z","steps":["trace[314708354] 'process raft request' (duration: 3.512011986s)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:24.43214Z","caller":"traceutil/trace.go:171","msg":"trace[1681946012] transaction","detail":"{read_only:false; response_revision:2522; number_of_response:1; }","duration":"3.509011789s","start":"2026-04-28T14:01:20.923117Z","end":"2026-04-28T14:01:24.432129Z","steps":["trace[1681946012] 'process raft request' (duration: 3.508830495s)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:24.432186Z","caller":"traceutil/trace.go:171","msg":"trace[2135929534] linearizableReadLoop","detail":"{readStateIndex:2625; appliedIndex:2620; }","duration":"2.904904764s","start":"2026-04-28T14:01:21.527267Z","end":"2026-04-28T14:01:24.432172Z","steps":["trace[2135929534] 'read index received' (duration: 2.891186315s)","trace[2135929534] 'applied index is now lower than readState.Index' (duration: 13.717319ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T14:01:24.432202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:20.923105Z","time spent":"3.50906586s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:01:24.43222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:20.922818Z","time spent":"3.509287475s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:01:24.432296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:20.919679Z","time spent":"3.512500507s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T14:01:24.432318Z","caller":"traceutil/trace.go:171","msg":"trace[80804534] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"2.568868347s","start":"2026-04-28T14:01:21.86338Z","end":"2026-04-28T14:01:24.432249Z","steps":["trace[80804534] 'process raft request' (duration: 2.568698863s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.432451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536361159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T14:01:24.432493Z","caller":"traceutil/trace.go:171","msg":"trace[167364087] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2523; }","duration":"1.53645399s","start":"2026-04-28T14:01:22.89603Z","end":"2026-04-28T14:01:24.432484Z","steps":["trace[167364087] 'agreement among raft nodes before linearized reading' (duration: 1.536387979s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.432505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.404706466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"warn","ts":"2026-04-28T14:01:24.432506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.526417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T14:01:24.432518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:22.896008Z","time spent":"1.536503692s","remote":"127.0.0.1:34366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-28T14:01:24.432531Z","caller":"traceutil/trace.go:171","msg":"trace[651881270] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2523; }","duration":"2.404798708s","start":"2026-04-28T14:01:22.027724Z","end":"2026-04-28T14:01:24.432522Z","steps":["trace[651881270] 'agreement among raft nodes before linearized reading' (duration: 2.404735877s)"],"step_count":1} {"level":"info","ts":"2026-04-28T14:01:24.432536Z","caller":"traceutil/trace.go:171","msg":"trace[1971145125] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2523; }","duration":"870.587857ms","start":"2026-04-28T14:01:23.561941Z","end":"2026-04-28T14:01:24.432529Z","steps":["trace[1971145125] 'agreement among raft nodes before linearized reading' (duration: 870.515406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.432564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:23.561929Z","time spent":"870.630078ms","remote":"127.0.0.1:34264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-28T14:01:24.432563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:22.027689Z","time spent":"2.40486762s","remote":"127.0.0.1:33988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":645,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-28T14:01:24.432645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.690592ms","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-28T14:01:24.432678Z","caller":"traceutil/trace.go:171","msg":"trace[1196312450] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2523; }","duration":"915.753183ms","start":"2026-04-28T14:01:23.516915Z","end":"2026-04-28T14:01:24.432668Z","steps":["trace[1196312450] 'agreement among raft nodes before linearized reading' (duration: 915.706002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.432644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:21.863356Z","time spent":"2.569120182s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T14:01:24.432746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.451250062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1788"} {"level":"info","ts":"2026-04-28T14:01:24.432773Z","caller":"traceutil/trace.go:171","msg":"trace[1284861637] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:2523; }","duration":"2.451330474s","start":"2026-04-28T14:01:21.981436Z","end":"2026-04-28T14:01:24.432767Z","steps":["trace[1284861637] 'agreement among raft nodes before linearized reading' (duration: 2.451235822s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.432779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.292522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T14:01:24.432819Z","caller":"traceutil/trace.go:171","msg":"trace[989721131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2523; }","duration":"834.370084ms","start":"2026-04-28T14:01:23.59844Z","end":"2026-04-28T14:01:24.43281Z","steps":["trace[989721131] 'agreement among raft nodes before linearized reading' (duration: 834.308572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.43283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409057076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.134\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-28T14:01:24.432847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:23.598419Z","time spent":"834.422184ms","remote":"127.0.0.1:33872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T14:01:24.432852Z","caller":"traceutil/trace.go:171","msg":"trace[1064102099] range","detail":"{range_begin:/registry/masterleases/199.19.213.134; range_end:; response_count:1; response_revision:2523; }","duration":"1.409122687s","start":"2026-04-28T14:01:23.023723Z","end":"2026-04-28T14:01:24.432845Z","steps":["trace[1064102099] 'agreement among raft nodes before linearized reading' (duration: 1.409007885s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.432848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431890509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T14:01:24.432908Z","caller":"traceutil/trace.go:171","msg":"trace[725591526] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2523; }","duration":"1.431991641s","start":"2026-04-28T14:01:23.000903Z","end":"2026-04-28T14:01:24.432894Z","steps":["trace[725591526] 'agreement among raft nodes before linearized reading' (duration: 1.43190245s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.432945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.478354141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-28T14:01:24.43287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.476035319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-28T14:01:24.432302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.905023696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T14:01:24.43298Z","caller":"traceutil/trace.go:171","msg":"trace[1037610835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2523; }","duration":"2.478423443s","start":"2026-04-28T14:01:21.954546Z","end":"2026-04-28T14:01:24.43297Z","steps":["trace[1037610835] 'agreement among raft nodes before linearized reading' (duration: 2.478341691s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.432793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:21.981406Z","time spent":"2.451380875s","remote":"127.0.0.1:33996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":5,"response size":1812,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"info","ts":"2026-04-28T14:01:24.43301Z","caller":"traceutil/trace.go:171","msg":"trace[514172571] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2523; }","duration":"2.905737272s","start":"2026-04-28T14:01:21.527263Z","end":"2026-04-28T14:01:24.433Z","steps":["trace[514172571] 'agreement among raft nodes before linearized reading' (duration: 2.904985325s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.433048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:21.527216Z","time spent":"2.905819884s","remote":"127.0.0.1:39848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-28T14:01:24.433007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:21.954525Z","time spent":"2.478476035s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T14:01:24.432876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:23.023662Z","time spent":"1.409209939s","remote":"127.0.0.1:33900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.134\" "} {"level":"warn","ts":"2026-04-28T14:01:24.432716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:23.516898Z","time spent":"915.806194ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"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-28T14:01:24.433297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.519195009s","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-28T14:01:24.432948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:23.000884Z","time spent":"1.432055122s","remote":"127.0.0.1:40038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-04-28T14:01:24.433371Z","caller":"traceutil/trace.go:171","msg":"trace[1099755167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2523; }","duration":"2.476193443s","start":"2026-04-28T14:01:21.956779Z","end":"2026-04-28T14:01:24.432973Z","steps":["trace[1099755167] 'agreement among raft nodes before linearized reading' (duration: 2.47602892s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.433429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:21.95675Z","time spent":"2.476661963s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T14:01:24.433337Z","caller":"traceutil/trace.go:171","msg":"trace[1040741924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2523; }","duration":"2.519279341s","start":"2026-04-28T14:01:21.914048Z","end":"2026-04-28T14:01:24.433327Z","steps":["trace[1040741924] 'agreement among raft nodes before linearized reading' (duration: 2.519175979s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.433478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.5699467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-28T14:01:24.432443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.879077612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T14:01:24.433508Z","caller":"traceutil/trace.go:171","msg":"trace[180043911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2523; }","duration":"2.570018962s","start":"2026-04-28T14:01:21.863482Z","end":"2026-04-28T14:01:24.433501Z","steps":["trace[180043911] 'agreement among raft nodes before linearized reading' (duration: 2.56994082s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.43353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:21.863453Z","time spent":"2.570072943s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T14:01:24.433528Z","caller":"traceutil/trace.go:171","msg":"trace[385586082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2523; }","duration":"2.880203857s","start":"2026-04-28T14:01:21.553315Z","end":"2026-04-28T14:01:24.433518Z","steps":["trace[385586082] 'agreement among raft nodes before linearized reading' (duration: 2.879042242s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T14:01:24.433565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:21.553291Z","time spent":"2.880264349s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T14:01:24.433479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T14:01:21.914024Z","time spent":"2.519444485s","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}