{"level":"warn","ts":"2026-05-06T05:04:29.815849Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T05:04:29.81595Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.91: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.91:2380","--initial-cluster=instance=https://199.19.213.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.91:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-06T05:04:29.816059Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T05:04:29.816079Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-06T05:04:29.816128Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-06T05:04:29.817015Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"]} {"level":"info","ts":"2026-05-06T05:04:29.817178Z","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.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.91:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-06T05:04:29.824375Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.8728ms"} {"level":"info","ts":"2026-05-06T05:04:29.834287Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2c24222821500990","cluster-id":"f38faa6ba493daad"} {"level":"info","ts":"2026-05-06T05:04:29.834353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T05:04:29.83445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 0"} {"level":"info","ts":"2026-05-06T05:04:29.834468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2c24222821500990 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T05:04:29.834486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 1"} {"level":"info","ts":"2026-05-06T05:04:29.834572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"warn","ts":"2026-05-06T05:04:29.847211Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T05:04:29.849083Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T05:04:29.85467Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-06T05:04:29.857915Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2c24222821500990","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T05:04:29.858007Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2c24222821500990","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T05:04:29.859272Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T05:04:29.859341Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T05:04:29.85936Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-06T05:04:29.860251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"info","ts":"2026-05-06T05:04:29.860737Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","added-peer-id":"2c24222821500990","added-peer-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-06T05:04:29.862461Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-06T05:04:29.862652Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-06T05:04:29.862671Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-06T05:04:29.863926Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2c24222821500990","initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T05:04:29.863961Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T05:04:30.835548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T05:04:30.835704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T05:04:30.835785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgPreVoteResp from 2c24222821500990 at term 1"} {"level":"info","ts":"2026-05-06T05:04:30.835821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became candidate at term 2"} {"level":"info","ts":"2026-05-06T05:04:30.835872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgVoteResp from 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-06T05:04:30.83598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became leader at term 2"} {"level":"info","ts":"2026-05-06T05:04:30.836049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2c24222821500990 elected leader 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-06T05:04:30.842277Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2c24222821500990","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.91:2379]}","request-path":"/0/members/2c24222821500990/attributes","cluster-id":"f38faa6ba493daad","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T05:04:30.842336Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:04:30.8424Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:04:30.842324Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:04:30.842948Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T05:04:30.843088Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T05:04:30.846896Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.91:2379"} {"level":"info","ts":"2026-05-06T05:04:30.847344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T05:04:30.8625Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:04:30.862786Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:04:30.862959Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:04:35.833517Z","caller":"traceutil/trace.go:171","msg":"trace[480432273] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"220.22805ms","start":"2026-05-06T05:04:35.61327Z","end":"2026-05-06T05:04:35.833498Z","steps":["trace[480432273] 'process raft request' (duration: 220.030475ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.833683Z","caller":"traceutil/trace.go:171","msg":"trace[1219250530] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"222.783183ms","start":"2026-05-06T05:04:35.610792Z","end":"2026-05-06T05:04:35.833575Z","steps":["trace[1219250530] 'process raft request' (duration: 132.36713ms)","trace[1219250530] 'compare' (duration: 89.85312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:04:35.833976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.888628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:35.834176Z","caller":"traceutil/trace.go:171","msg":"trace[205563644] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:237; }","duration":"215.110673ms","start":"2026-05-06T05:04:35.619032Z","end":"2026-05-06T05:04:35.834143Z","steps":["trace[205563644] 'agreement among raft nodes before linearized reading' (duration: 214.812466ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.834292Z","caller":"traceutil/trace.go:171","msg":"trace[1679718211] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"213.984976ms","start":"2026-05-06T05:04:35.620287Z","end":"2026-05-06T05:04:35.834272Z","steps":["trace[1679718211] 'process raft request' (duration: 213.587196ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.834443Z","caller":"traceutil/trace.go:171","msg":"trace[361881573] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"120.009464ms","start":"2026-05-06T05:04:35.714394Z","end":"2026-05-06T05:04:35.834403Z","steps":["trace[361881573] 'process raft request' (duration: 119.948212ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.834464Z","caller":"traceutil/trace.go:171","msg":"trace[140147970] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"213.633207ms","start":"2026-05-06T05:04:35.620794Z","end":"2026-05-06T05:04:35.834427Z","steps":["trace[140147970] 'process raft request' (duration: 213.254857ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.834517Z","caller":"traceutil/trace.go:171","msg":"trace[1995512290] transaction","detail":"{read_only:false; number_of_response:0; response_revision:240; }","duration":"121.794789ms","start":"2026-05-06T05:04:35.712678Z","end":"2026-05-06T05:04:35.834473Z","steps":["trace[1995512290] 'process raft request' (duration: 121.596804ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.834897Z","caller":"traceutil/trace.go:171","msg":"trace[866570231] transaction","detail":"{read_only:false; number_of_response:0; response_revision:239; }","duration":"138.43937ms","start":"2026-05-06T05:04:35.696448Z","end":"2026-05-06T05:04:35.834887Z","steps":["trace[866570231] 'process raft request' (duration: 137.670051ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.834933Z","caller":"traceutil/trace.go:171","msg":"trace[328097400] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"122.806524ms","start":"2026-05-06T05:04:35.712109Z","end":"2026-05-06T05:04:35.834916Z","steps":["trace[328097400] 'process raft request' (duration: 122.055415ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.835025Z","caller":"traceutil/trace.go:171","msg":"trace[1482426956] transaction","detail":"{read_only:false; number_of_response:0; response_revision:240; }","duration":"122.594288ms","start":"2026-05-06T05:04:35.712417Z","end":"2026-05-06T05:04:35.835011Z","steps":["trace[1482426956] 'process raft request' (duration: 121.824289ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.834946Z","caller":"traceutil/trace.go:171","msg":"trace[1248169805] transaction","detail":"{read_only:false; number_of_response:0; response_revision:240; }","duration":"121.930731ms","start":"2026-05-06T05:04:35.712971Z","end":"2026-05-06T05:04:35.834902Z","steps":["trace[1248169805] 'process raft request' (duration: 121.336097ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:35.835126Z","caller":"traceutil/trace.go:171","msg":"trace[461420049] linearizableReadLoop","detail":"{readStateIndex:243; appliedIndex:241; }","duration":"214.349895ms","start":"2026-05-06T05:04:35.619041Z","end":"2026-05-06T05:04:35.833391Z","steps":["trace[461420049] 'read index received' (duration: 124.196918ms)","trace[461420049] 'applied index is now lower than readState.Index' (duration: 90.151617ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:36.034132Z","caller":"traceutil/trace.go:171","msg":"trace[1477209895] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"101.22316ms","start":"2026-05-06T05:04:35.932873Z","end":"2026-05-06T05:04:36.034096Z","steps":["trace[1477209895] 'process raft request' (duration: 15.950114ms)","trace[1477209895] 'compare' (duration: 84.932088ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:43.861979Z","caller":"traceutil/trace.go:171","msg":"trace[1580133098] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"205.537946ms","start":"2026-05-06T05:04:43.656412Z","end":"2026-05-06T05:04:43.86195Z","steps":["trace[1580133098] 'read index received' (duration: 205.27461ms)","trace[1580133098] 'applied index is now lower than readState.Index' (duration: 261.806µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:43.862083Z","caller":"traceutil/trace.go:171","msg":"trace[1138076116] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"239.482865ms","start":"2026-05-06T05:04:43.622569Z","end":"2026-05-06T05:04:43.862052Z","steps":["trace[1138076116] 'process raft request' (duration: 239.213588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:43.862129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.68865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:43.862182Z","caller":"traceutil/trace.go:171","msg":"trace[1806759935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"205.788313ms","start":"2026-05-06T05:04:43.656373Z","end":"2026-05-06T05:04:43.862161Z","steps":["trace[1806759935] 'agreement among raft nodes before linearized reading' (duration: 205.69535ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:44.219835Z","caller":"traceutil/trace.go:171","msg":"trace[1776270647] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"350.445965ms","start":"2026-05-06T05:04:43.869281Z","end":"2026-05-06T05:04:44.219726Z","steps":["trace[1776270647] 'process raft request' (duration: 261.653852ms)","trace[1776270647] 'compare' (duration: 88.67207ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:04:44.220297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:43.869259Z","time spent":"350.642611ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:04:44.294044Z","caller":"traceutil/trace.go:171","msg":"trace[1687539098] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:380; }","duration":"355.720867ms","start":"2026-05-06T05:04:43.938306Z","end":"2026-05-06T05:04:44.294027Z","steps":["trace[1687539098] 'read index received' (duration: 192.639769ms)","trace[1687539098] 'applied index is now lower than readState.Index' (duration: 163.080198ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:04:44.294154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.840999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T05:04:44.294175Z","caller":"traceutil/trace.go:171","msg":"trace[1759664072] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:368; }","duration":"355.926311ms","start":"2026-05-06T05:04:43.938241Z","end":"2026-05-06T05:04:44.294167Z","steps":["trace[1759664072] 'agreement among raft nodes before linearized reading' (duration: 355.863529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:44.294195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:43.938219Z","time spent":"355.971993ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"info","ts":"2026-05-06T05:04:44.29418Z","caller":"traceutil/trace.go:171","msg":"trace[36852606] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"420.511666ms","start":"2026-05-06T05:04:43.873619Z","end":"2026-05-06T05:04:44.294131Z","steps":["trace[36852606] 'process raft request' (duration: 420.311281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:44.294503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:43.873575Z","time spent":"420.807323ms","remote":"127.0.0.1:60550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:04:44.448364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.763153ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-06T05:04:44.448458Z","caller":"traceutil/trace.go:171","msg":"trace[460947753] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"140.444699ms","start":"2026-05-06T05:04:44.308001Z","end":"2026-05-06T05:04:44.448446Z","steps":["trace[460947753] 'read index received' (duration: 19.389119ms)","trace[460947753] 'applied index is now lower than readState.Index' (duration: 121.05281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:04:44.448539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.531801ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:44.448594Z","caller":"traceutil/trace.go:171","msg":"trace[1645321409] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:368; }","duration":"140.593302ms","start":"2026-05-06T05:04:44.307993Z","end":"2026-05-06T05:04:44.448587Z","steps":["trace[1645321409] 'agreement among raft nodes before linearized reading' (duration: 140.51864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:44.642779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.859722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-06T05:04:44.642679Z","caller":"traceutil/trace.go:171","msg":"trace[1839428855] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:384; }","duration":"162.76018ms","start":"2026-05-06T05:04:44.4799Z","end":"2026-05-06T05:04:44.64266Z","steps":["trace[1839428855] 'read index received' (duration: 147.420061ms)","trace[1839428855] 'applied index is now lower than readState.Index' (duration: 15.339259ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:44.64284Z","caller":"traceutil/trace.go:171","msg":"trace[1184188214] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:371; }","duration":"162.973795ms","start":"2026-05-06T05:04:44.479856Z","end":"2026-05-06T05:04:44.64283Z","steps":["trace[1184188214] 'agreement among raft nodes before linearized reading' (duration: 162.839802ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:44.642893Z","caller":"traceutil/trace.go:171","msg":"trace[862444290] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"189.928421ms","start":"2026-05-06T05:04:44.452944Z","end":"2026-05-06T05:04:44.642872Z","steps":["trace[862444290] 'process raft request' (duration: 189.684395ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:44.642846Z","caller":"traceutil/trace.go:171","msg":"trace[1571156139] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"190.433724ms","start":"2026-05-06T05:04:44.452355Z","end":"2026-05-06T05:04:44.642788Z","steps":["trace[1571156139] 'process raft request' (duration: 174.950742ms)","trace[1571156139] 'compare' (duration: 15.226966ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:44.719924Z","caller":"traceutil/trace.go:171","msg":"trace[1592043947] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"236.929462ms","start":"2026-05-06T05:04:44.482969Z","end":"2026-05-06T05:04:44.719898Z","steps":["trace[1592043947] 'process raft request' (duration: 236.473501ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:44.85334Z","caller":"traceutil/trace.go:171","msg":"trace[1480330703] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"119.159053ms","start":"2026-05-06T05:04:44.734158Z","end":"2026-05-06T05:04:44.853317Z","steps":["trace[1480330703] 'process raft request' (duration: 109.166086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:51.37907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241653627,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T05:04:51.486034Z","caller":"traceutil/trace.go:171","msg":"trace[2003967394] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"607.70894ms","start":"2026-05-06T05:04:50.878295Z","end":"2026-05-06T05:04:51.486004Z","steps":["trace[2003967394] 'read index received' (duration: 607.427303ms)","trace[2003967394] 'applied index is now lower than readState.Index' (duration: 280.167µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:51.486087Z","caller":"traceutil/trace.go:171","msg":"trace[987675948] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"816.544249ms","start":"2026-05-06T05:04:50.669518Z","end":"2026-05-06T05:04:51.486062Z","steps":["trace[987675948] 'process raft request' (duration: 816.310083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:51.486189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.887815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:04:51.486221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.387878ms","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-05-06T05:04:51.486249Z","caller":"traceutil/trace.go:171","msg":"trace[1542733588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"607.964717ms","start":"2026-05-06T05:04:50.878269Z","end":"2026-05-06T05:04:51.486234Z","steps":["trace[1542733588] 'agreement among raft nodes before linearized reading' (duration: 607.855024ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:51.486264Z","caller":"traceutil/trace.go:171","msg":"trace[644064323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:389; }","duration":"555.4753ms","start":"2026-05-06T05:04:50.930772Z","end":"2026-05-06T05:04:51.486247Z","steps":["trace[644064323] 'agreement among raft nodes before linearized reading' (duration: 555.393588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:51.486256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:50.669497Z","time spent":"816.653232ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:04:51.48629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:50.878197Z","time spent":"608.08533ms","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:04:51.486312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:50.930746Z","time spent":"555.556472ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T05:04:51.486328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.613248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-06T05:04:51.486454Z","caller":"traceutil/trace.go:171","msg":"trace[1933761331] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:389; }","duration":"294.758661ms","start":"2026-05-06T05:04:51.191646Z","end":"2026-05-06T05:04:51.486405Z","steps":["trace[1933761331] 'agreement among raft nodes before linearized reading' (duration: 294.587177ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:57.168593Z","caller":"traceutil/trace.go:171","msg":"trace[1959109583] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"290.391152ms","start":"2026-05-06T05:04:56.878167Z","end":"2026-05-06T05:04:57.168558Z","steps":["trace[1959109583] 'read index received' (duration: 290.139086ms)","trace[1959109583] 'applied index is now lower than readState.Index' (duration: 250.516µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:57.168665Z","caller":"traceutil/trace.go:171","msg":"trace[2093028109] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"465.152318ms","start":"2026-05-06T05:04:56.703477Z","end":"2026-05-06T05:04:57.168629Z","steps":["trace[2093028109] 'process raft request' (duration: 464.874771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:57.168732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.557106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:57.16879Z","caller":"traceutil/trace.go:171","msg":"trace[1139810427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"290.661449ms","start":"2026-05-06T05:04:56.878113Z","end":"2026-05-06T05:04:57.168775Z","steps":["trace[1139810427] 'agreement among raft nodes before linearized reading' (duration: 290.570346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:57.168838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:56.703445Z","time spent":"465.310242ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:04:59.866518Z","caller":"traceutil/trace.go:171","msg":"trace[1695465767] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:457; }","duration":"154.199258ms","start":"2026-05-06T05:04:59.712292Z","end":"2026-05-06T05:04:59.866492Z","steps":["trace[1695465767] 'read index received' (duration: 150.832455ms)","trace[1695465767] 'applied index is now lower than readState.Index' (duration: 3.365653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:04:59.866659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.345112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:59.866701Z","caller":"traceutil/trace.go:171","msg":"trace[1148743059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:439; }","duration":"154.425304ms","start":"2026-05-06T05:04:59.712256Z","end":"2026-05-06T05:04:59.866681Z","steps":["trace[1148743059] 'agreement among raft nodes before linearized reading' (duration: 154.331632ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:59.866989Z","caller":"traceutil/trace.go:171","msg":"trace[268568797] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"255.632784ms","start":"2026-05-06T05:04:59.611345Z","end":"2026-05-06T05:04:59.866978Z","steps":["trace[268568797] 'process raft request' (duration: 251.865641ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:05.328925Z","caller":"traceutil/trace.go:171","msg":"trace[882558071] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"338.588193ms","start":"2026-05-06T05:05:04.99029Z","end":"2026-05-06T05:05:05.328878Z","steps":["trace[882558071] 'process raft request' (duration: 338.302676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.329194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:04.99025Z","time spent":"338.837489ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:05:05.338514Z","caller":"traceutil/trace.go:171","msg":"trace[402878158] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"343.057254ms","start":"2026-05-06T05:05:04.995436Z","end":"2026-05-06T05:05:05.338493Z","steps":["trace[402878158] 'process raft request' (duration: 342.831918ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:05.338626Z","caller":"traceutil/trace.go:171","msg":"trace[1334710486] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"333.759844ms","start":"2026-05-06T05:05:05.00485Z","end":"2026-05-06T05:05:05.33861Z","steps":["trace[1334710486] 'process raft request' (duration: 333.58837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.338679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:04.995412Z","time spent":"343.203527ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T05:05:05.338711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:05.004831Z","time spent":"333.839025ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:05:05.338686Z","caller":"traceutil/trace.go:171","msg":"trace[2042820042] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"342.548581ms","start":"2026-05-06T05:05:04.995995Z","end":"2026-05-06T05:05:05.338543Z","steps":["trace[2042820042] 'process raft request' (duration: 342.395207ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:05.338847Z","caller":"traceutil/trace.go:171","msg":"trace[814141619] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"333.734254ms","start":"2026-05-06T05:05:05.005109Z","end":"2026-05-06T05:05:05.338843Z","steps":["trace[814141619] 'process raft request' (duration: 333.488018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.338873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:05.005105Z","time spent":"333.757654ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:05:05.338923Z","caller":"traceutil/trace.go:171","msg":"trace[2093451429] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"331.550189ms","start":"2026-05-06T05:05:05.007367Z","end":"2026-05-06T05:05:05.338917Z","steps":["trace[2093451429] 'process raft request' (duration: 331.286703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.338941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:05.00736Z","time spent":"331.57301ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:05:05.339018Z","caller":"traceutil/trace.go:171","msg":"trace[2048144402] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"320.048285ms","start":"2026-05-06T05:05:05.018965Z","end":"2026-05-06T05:05:05.339013Z","steps":["trace[2048144402] 'process raft request' (duration: 319.885521ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:05.339023Z","caller":"traceutil/trace.go:171","msg":"trace[740879929] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:505; }","duration":"133.228151ms","start":"2026-05-06T05:05:05.205783Z","end":"2026-05-06T05:05:05.339011Z","steps":["trace[740879929] 'read index received' (duration: 122.886965ms)","trace[740879929] 'applied index is now lower than readState.Index' (duration: 10.340376ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:05:05.339043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:05.018937Z","time spent":"320.095406ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:05:05.339087Z","caller":"traceutil/trace.go:171","msg":"trace[2025425707] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"323.948662ms","start":"2026-05-06T05:05:05.015133Z","end":"2026-05-06T05:05:05.339082Z","steps":["trace[2025425707] 'process raft request' (duration: 323.662884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.33911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:05.015126Z","time spent":"323.974062ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T05:05:05.339114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.320333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-06T05:05:05.339143Z","caller":"traceutil/trace.go:171","msg":"trace[215710526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:497; }","duration":"133.399395ms","start":"2026-05-06T05:05:05.205736Z","end":"2026-05-06T05:05:05.339135Z","steps":["trace[215710526] 'agreement among raft nodes before linearized reading' (duration: 133.316303ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:05.339187Z","caller":"traceutil/trace.go:171","msg":"trace[760627968] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"305.858175ms","start":"2026-05-06T05:05:05.033324Z","end":"2026-05-06T05:05:05.339182Z","steps":["trace[760627968] 'process raft request' (duration: 305.590858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.339212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:05.033291Z","time spent":"305.910986ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:05:05.339249Z","caller":"traceutil/trace.go:171","msg":"trace[691821505] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"162.689459ms","start":"2026-05-06T05:05:05.176555Z","end":"2026-05-06T05:05:05.339245Z","steps":["trace[691821505] 'process raft request' (duration: 162.412682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.339175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:04.995975Z","time spent":"342.93854ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:05:05.338811Z","caller":"traceutil/trace.go:171","msg":"trace[1289917081] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"333.853856ms","start":"2026-05-06T05:05:05.004952Z","end":"2026-05-06T05:05:05.338806Z","steps":["trace[1289917081] 'process raft request' (duration: 333.598239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.339395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:05.00495Z","time spent":"334.423151ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:05:05.338829Z","caller":"traceutil/trace.go:171","msg":"trace[1207859023] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"333.905267ms","start":"2026-05-06T05:05:05.004919Z","end":"2026-05-06T05:05:05.338824Z","steps":["trace[1207859023] 'process raft request' (duration: 333.561019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.339475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:05.004916Z","time spent":"334.545303ms","remote":"127.0.0.1:60412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T05:05:11.686354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.131149ms","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":"info","ts":"2026-05-06T05:05:11.686455Z","caller":"traceutil/trace.go:171","msg":"trace[1003609239] 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:593; }","duration":"216.278562ms","start":"2026-05-06T05:05:11.470159Z","end":"2026-05-06T05:05:11.686437Z","steps":["trace[1003609239] 'count revisions from in-memory index tree' (duration: 216.096537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:11.686487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.338274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:05:11.686565Z","caller":"traceutil/trace.go:171","msg":"trace[1401015969] 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:593; }","duration":"216.434236ms","start":"2026-05-06T05:05:11.470118Z","end":"2026-05-06T05:05:11.686552Z","steps":["trace[1401015969] 'range keys from in-memory index tree' (duration: 216.259332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:11.686725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.662327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:05:11.686754Z","caller":"traceutil/trace.go:171","msg":"trace[1715831510] 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:593; }","duration":"215.714938ms","start":"2026-05-06T05:05:11.471031Z","end":"2026-05-06T05:05:11.686746Z","steps":["trace[1715831510] 'range keys from in-memory index tree' (duration: 215.615776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:11.686787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.079476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:05:11.686847Z","caller":"traceutil/trace.go:171","msg":"trace[890685372] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:593; }","duration":"235.165879ms","start":"2026-05-06T05:05:11.451671Z","end":"2026-05-06T05:05:11.686837Z","steps":["trace[890685372] 'count revisions from in-memory index tree' (duration: 235.004944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:11.687038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.132208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:05:11.687091Z","caller":"traceutil/trace.go:171","msg":"trace[554496159] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:593; }","duration":"235.23016ms","start":"2026-05-06T05:05:11.451852Z","end":"2026-05-06T05:05:11.687083Z","steps":["trace[554496159] 'range keys from in-memory index tree' (duration: 235.082236ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:11.942093Z","caller":"traceutil/trace.go:171","msg":"trace[798681702] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"228.883433ms","start":"2026-05-06T05:05:11.713184Z","end":"2026-05-06T05:05:11.942067Z","steps":["trace[798681702] 'read index received' (duration: 227.980391ms)","trace[798681702] 'applied index is now lower than readState.Index' (duration: 901.842µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:05:11.942404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.202102ms","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-05-06T05:05:11.942522Z","caller":"traceutil/trace.go:171","msg":"trace[1120588066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"229.383316ms","start":"2026-05-06T05:05:11.713108Z","end":"2026-05-06T05:05:11.942491Z","steps":["trace[1120588066] 'agreement among raft nodes before linearized reading' (duration: 229.096299ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:11.942169Z","caller":"traceutil/trace.go:171","msg":"trace[611155149] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"325.764147ms","start":"2026-05-06T05:05:11.616373Z","end":"2026-05-06T05:05:11.942137Z","steps":["trace[611155149] 'process raft request' (duration: 325.10094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:11.942811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.71332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dn6gz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-06T05:05:11.943032Z","caller":"traceutil/trace.go:171","msg":"trace[803480098] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dn6gz; range_end:; response_count:1; response_revision:594; }","duration":"209.949726ms","start":"2026-05-06T05:05:11.733066Z","end":"2026-05-06T05:05:11.943016Z","steps":["trace[803480098] 'agreement among raft nodes before linearized reading' (duration: 209.341951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:11.943026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:11.616338Z","time spent":"326.522066ms","remote":"127.0.0.1:60446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:05:12.173406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.31054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:05:12.173775Z","caller":"traceutil/trace.go:171","msg":"trace[904140458] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"224.755601ms","start":"2026-05-06T05:05:11.948997Z","end":"2026-05-06T05:05:12.173753Z","steps":["trace[904140458] 'process raft request' (duration: 48.762224ms)","trace[904140458] 'compare' (duration: 175.163417ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:12.211784Z","caller":"traceutil/trace.go:171","msg":"trace[1789746879] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"220.608429ms","start":"2026-05-06T05:05:11.991152Z","end":"2026-05-06T05:05:12.211761Z","steps":["trace[1789746879] 'read index received' (duration: 6.577233ms)","trace[1789746879] 'applied index is now lower than readState.Index' (duration: 214.030376ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:12.211924Z","caller":"traceutil/trace.go:171","msg":"trace[2024670804] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"215.078412ms","start":"2026-05-06T05:05:11.996811Z","end":"2026-05-06T05:05:12.211889Z","steps":["trace[2024670804] 'process raft request' (duration: 214.906728ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:12.212028Z","caller":"traceutil/trace.go:171","msg":"trace[2073368286] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"254.863155ms","start":"2026-05-06T05:05:11.957076Z","end":"2026-05-06T05:05:12.211939Z","steps":["trace[2073368286] 'process raft request' (duration: 254.521676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:12.212248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.074961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T05:05:12.212295Z","caller":"traceutil/trace.go:171","msg":"trace[1429912941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"221.162022ms","start":"2026-05-06T05:05:11.991122Z","end":"2026-05-06T05:05:12.212284Z","steps":["trace[1429912941] 'agreement among raft nodes before linearized reading' (duration: 220.725931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:12.853114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241654062,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:05:13.236719Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.003491506s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T05:05:13.820008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.02529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:05:13.82062Z","caller":"traceutil/trace.go:171","msg":"trace[417062594] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"1.467878836s","start":"2026-05-06T05:05:12.352701Z","end":"2026-05-06T05:05:13.82058Z","steps":["trace[417062594] 'read index received' (duration: 884.18767ms)","trace[417062594] 'applied index is now lower than readState.Index' (duration: 583.687376ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:13.820649Z","caller":"traceutil/trace.go:171","msg":"trace[2044049812] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.58747377s","start":"2026-05-06T05:05:12.233129Z","end":"2026-05-06T05:05:13.820603Z","steps":["trace[2044049812] 'process raft request' (duration: 1.003776823s)","trace[2044049812] 'compare' (duration: 582.643441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:05:13.820787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468071741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T05:05:13.820859Z","caller":"traceutil/trace.go:171","msg":"trace[712174424] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"1.468187244s","start":"2026-05-06T05:05:12.35266Z","end":"2026-05-06T05:05:13.820847Z","steps":["trace[712174424] 'agreement among raft nodes before linearized reading' (duration: 1.468076381s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:13.820885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:12.352641Z","time spent":"1.468239055s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T05:05:13.820885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:12.233115Z","time spent":"1.587681565s","remote":"127.0.0.1:60550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T05:05:13.820941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.192717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T05:05:13.820984Z","caller":"traceutil/trace.go:171","msg":"trace[175910313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"373.29448ms","start":"2026-05-06T05:05:13.447678Z","end":"2026-05-06T05:05:13.820973Z","steps":["trace[175910313] 'agreement among raft nodes before linearized reading' (duration: 373.109536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:13.82102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:13.44766Z","time spent":"373.354471ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T05:05:29.956743Z","caller":"traceutil/trace.go:171","msg":"trace[865329879] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"100.834691ms","start":"2026-05-06T05:05:29.855882Z","end":"2026-05-06T05:05:29.956716Z","steps":["trace[865329879] 'process raft request' (duration: 100.327528ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:05.348364Z","caller":"traceutil/trace.go:171","msg":"trace[825943050] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"350.454124ms","start":"2026-05-06T05:06:04.997881Z","end":"2026-05-06T05:06:05.348335Z","steps":["trace[825943050] 'process raft request' (duration: 350.294111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:05.34883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:04.997852Z","time spent":"350.636369ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:06.155571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.606065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:06:06.155666Z","caller":"traceutil/trace.go:171","msg":"trace[368239460] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"855.938355ms","start":"2026-05-06T05:06:05.299714Z","end":"2026-05-06T05:06:06.155652Z","steps":["trace[368239460] 'read index received' (duration: 48.98693ms)","trace[368239460] 'applied index is now lower than readState.Index' (duration: 806.950735ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:06.155877Z","caller":"traceutil/trace.go:171","msg":"trace[744164131] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.140623145s","start":"2026-05-06T05:06:05.015213Z","end":"2026-05-06T05:06:06.155837Z","steps":["trace[744164131] 'process raft request' (duration: 729.687472ms)","trace[744164131] 'compare' (duration: 410.507892ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:06.155975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.263973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:06.156Z","caller":"traceutil/trace.go:171","msg":"trace[486589438] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:980; }","duration":"856.292293ms","start":"2026-05-06T05:06:05.299701Z","end":"2026-05-06T05:06:06.155993Z","steps":["trace[486589438] 'agreement among raft nodes before linearized reading' (duration: 856.247132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:06.156009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:05.015195Z","time spent":"1.140743238s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:06.156025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:05.299641Z","time spent":"856.377436ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:06:06.156013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.804454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:06.156073Z","caller":"traceutil/trace.go:171","msg":"trace[1095462350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"277.891286ms","start":"2026-05-06T05:06:05.878167Z","end":"2026-05-06T05:06:06.156059Z","steps":["trace[1095462350] 'agreement among raft nodes before linearized reading' (duration: 277.776673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:06.156085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.536926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:06:06.156155Z","caller":"traceutil/trace.go:171","msg":"trace[630247996] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:980; }","duration":"279.648989ms","start":"2026-05-06T05:06:05.876489Z","end":"2026-05-06T05:06:06.156138Z","steps":["trace[630247996] 'agreement among raft nodes before linearized reading' (duration: 279.524966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:06.156186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.451554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T05:06:06.156235Z","caller":"traceutil/trace.go:171","msg":"trace[241962731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:980; }","duration":"633.532806ms","start":"2026-05-06T05:06:05.522688Z","end":"2026-05-06T05:06:06.156221Z","steps":["trace[241962731] 'agreement among raft nodes before linearized reading' (duration: 633.144507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:06.156268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:05.522672Z","time spent":"633.585877ms","remote":"127.0.0.1:60638","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":"info","ts":"2026-05-06T05:06:06.322988Z","caller":"traceutil/trace.go:171","msg":"trace[1487950658] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"147.127985ms","start":"2026-05-06T05:06:06.175842Z","end":"2026-05-06T05:06:06.32297Z","steps":["trace[1487950658] 'read index received' (duration: 146.953991ms)","trace[1487950658] 'applied index is now lower than readState.Index' (duration: 173.194µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:06.323102Z","caller":"traceutil/trace.go:171","msg":"trace[1872538844] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"163.552221ms","start":"2026-05-06T05:06:06.159492Z","end":"2026-05-06T05:06:06.323044Z","steps":["trace[1872538844] 'process raft request' (duration: 163.344836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:06.3232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.330729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T05:06:06.323231Z","caller":"traceutil/trace.go:171","msg":"trace[288715572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:981; }","duration":"137.37483ms","start":"2026-05-06T05:06:06.185847Z","end":"2026-05-06T05:06:06.323222Z","steps":["trace[288715572] 'agreement among raft nodes before linearized reading' (duration: 137.222997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:06.323282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.419062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-06T05:06:06.323375Z","caller":"traceutil/trace.go:171","msg":"trace[383265108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"147.567065ms","start":"2026-05-06T05:06:06.175791Z","end":"2026-05-06T05:06:06.323358Z","steps":["trace[383265108] 'agreement among raft nodes before linearized reading' (duration: 147.283259ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:26.123989Z","caller":"traceutil/trace.go:171","msg":"trace[1681632110] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"276.714837ms","start":"2026-05-06T05:06:25.847244Z","end":"2026-05-06T05:06:26.123959Z","steps":["trace[1681632110] 'read index received' (duration: 276.579104ms)","trace[1681632110] 'applied index is now lower than readState.Index' (duration: 134.663µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:26.124047Z","caller":"traceutil/trace.go:171","msg":"trace[108358929] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"361.636924ms","start":"2026-05-06T05:06:25.762361Z","end":"2026-05-06T05:06:26.123998Z","steps":["trace[108358929] 'process raft request' (duration: 361.428759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:26.124148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.879861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:26.12425Z","caller":"traceutil/trace.go:171","msg":"trace[1817570645] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1045; }","duration":"277.032325ms","start":"2026-05-06T05:06:25.847201Z","end":"2026-05-06T05:06:26.124233Z","steps":["trace[1817570645] 'agreement among raft nodes before linearized reading' (duration: 276.885301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:26.124244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:25.762319Z","time spent":"361.811058ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:06:26.354332Z","caller":"traceutil/trace.go:171","msg":"trace[350825626] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"122.963463ms","start":"2026-05-06T05:06:26.231327Z","end":"2026-05-06T05:06:26.35429Z","steps":["trace[350825626] 'process raft request' (duration: 111.385714ms)","trace[350825626] 'compare' (duration: 11.402445ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:26.646055Z","caller":"traceutil/trace.go:171","msg":"trace[1902106570] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"124.494251ms","start":"2026-05-06T05:06:26.521532Z","end":"2026-05-06T05:06:26.646026Z","steps":["trace[1902106570] 'process raft request' (duration: 111.899307ms)","trace[1902106570] 'compare' (duration: 12.398439ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:28.673611Z","caller":"traceutil/trace.go:171","msg":"trace[960657567] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"237.042782ms","start":"2026-05-06T05:06:28.436533Z","end":"2026-05-06T05:06:28.673576Z","steps":["trace[960657567] 'process raft request' (duration: 236.875068ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:28.733806Z","caller":"traceutil/trace.go:171","msg":"trace[1424730871] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1093; }","duration":"275.98209ms","start":"2026-05-06T05:06:28.457794Z","end":"2026-05-06T05:06:28.733776Z","steps":["trace[1424730871] 'read index received' (duration: 215.696737ms)","trace[1424730871] 'applied index is now lower than readState.Index' (duration: 60.283973ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:28.733973Z","caller":"traceutil/trace.go:171","msg":"trace[1569161030] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"279.440674ms","start":"2026-05-06T05:06:28.454476Z","end":"2026-05-06T05:06:28.733917Z","steps":["trace[1569161030] 'process raft request' (duration: 248.641151ms)","trace[1569161030] 'compare' (duration: 30.521856ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:28.734111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.288648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T05:06:28.734207Z","caller":"traceutil/trace.go:171","msg":"trace[377594501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"276.432101ms","start":"2026-05-06T05:06:28.457742Z","end":"2026-05-06T05:06:28.734174Z","steps":["trace[377594501] 'agreement among raft nodes before linearized reading' (duration: 276.181255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:28.73433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.645067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T05:06:28.734447Z","caller":"traceutil/trace.go:171","msg":"trace[196031658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"237.827491ms","start":"2026-05-06T05:06:28.496605Z","end":"2026-05-06T05:06:28.734433Z","steps":["trace[196031658] 'agreement among raft nodes before linearized reading' (duration: 237.477773ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:28.94342Z","caller":"traceutil/trace.go:171","msg":"trace[653165295] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"202.52053ms","start":"2026-05-06T05:06:28.740873Z","end":"2026-05-06T05:06:28.943394Z","steps":["trace[653165295] 'process raft request' (duration: 183.01102ms)","trace[653165295] 'compare' (duration: 19.397667ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:29.237942Z","caller":"traceutil/trace.go:171","msg":"trace[1676200666] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"496.725199ms","start":"2026-05-06T05:06:28.741194Z","end":"2026-05-06T05:06:29.237919Z","steps":["trace[1676200666] 'process raft request' (duration: 496.546365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:29.238074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:28.741155Z","time spent":"496.845852ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:06:29.238084Z","caller":"traceutil/trace.go:171","msg":"trace[1982521264] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"496.808481ms","start":"2026-05-06T05:06:28.741243Z","end":"2026-05-06T05:06:29.238051Z","steps":["trace[1982521264] 'process raft request' (duration: 496.539735ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:29.238158Z","caller":"traceutil/trace.go:171","msg":"trace[955905697] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1095; }","duration":"358.732424ms","start":"2026-05-06T05:06:28.879389Z","end":"2026-05-06T05:06:29.238121Z","steps":["trace[955905697] 'read index received' (duration: 44.510092ms)","trace[955905697] 'applied index is now lower than readState.Index' (duration: 314.217212ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:29.238214Z","caller":"traceutil/trace.go:171","msg":"trace[2073495682] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"497.002956ms","start":"2026-05-06T05:06:28.7412Z","end":"2026-05-06T05:06:29.238203Z","steps":["trace[2073495682] 'process raft request' (duration: 409.557909ms)","trace[2073495682] 'compare' (duration: 86.847902ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:29.23826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:28.741186Z","time spent":"497.049457ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:29.23829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:28.741217Z","time spent":"496.942595ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:29.374912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.068353ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-06T05:06:29.374927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.51468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:29.375241Z","caller":"traceutil/trace.go:171","msg":"trace[653390784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"495.891009ms","start":"2026-05-06T05:06:28.879332Z","end":"2026-05-06T05:06:29.375223Z","steps":["trace[653390784] 'agreement among raft nodes before linearized reading' (duration: 358.887927ms)","trace[653390784] 'range keys from in-memory index tree' (duration: 136.634212ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:29.375306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:28.879298Z","time spent":"495.989881ms","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:06:37.514657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.289651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-06T05:06:37.514781Z","caller":"traceutil/trace.go:171","msg":"trace[1658977505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1083; }","duration":"223.470585ms","start":"2026-05-06T05:06:37.291282Z","end":"2026-05-06T05:06:37.514753Z","steps":["trace[1658977505] 'range keys from in-memory index tree' (duration: 223.061455ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:42.646351Z","caller":"traceutil/trace.go:171","msg":"trace[518216699] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"200.686019ms","start":"2026-05-06T05:06:42.445644Z","end":"2026-05-06T05:06:42.64633Z","steps":["trace[518216699] 'process raft request' (duration: 200.578817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:43.923609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655260,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T05:06:44.097659Z","caller":"traceutil/trace.go:171","msg":"trace[447524212] linearizableReadLoop","detail":"{readStateIndex:1198; appliedIndex:1197; }","duration":"675.033774ms","start":"2026-05-06T05:06:43.422604Z","end":"2026-05-06T05:06:44.097638Z","steps":["trace[447524212] 'read index received' (duration: 674.87955ms)","trace[447524212] 'applied index is now lower than readState.Index' (duration: 150.394µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:44.097757Z","caller":"traceutil/trace.go:171","msg":"trace[1472196575] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"731.090102ms","start":"2026-05-06T05:06:43.366659Z","end":"2026-05-06T05:06:44.09775Z","steps":["trace[1472196575] 'process raft request' (duration: 730.843036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:44.097829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:43.366647Z","time spent":"731.126743ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:44.097963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.898852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-06T05:06:44.097944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.019621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-06T05:06:44.098028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.434503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T05:06:44.098044Z","caller":"traceutil/trace.go:171","msg":"trace[136607647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1152; }","duration":"675.484234ms","start":"2026-05-06T05:06:43.422555Z","end":"2026-05-06T05:06:44.09804Z","steps":["trace[136607647] 'agreement among raft nodes before linearized reading' (duration: 675.443673ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:44.098027Z","caller":"traceutil/trace.go:171","msg":"trace[685007970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1152; }","duration":"424.145635ms","start":"2026-05-06T05:06:43.673861Z","end":"2026-05-06T05:06:44.098007Z","steps":["trace[685007970] 'agreement among raft nodes before linearized reading' (duration: 423.914989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:44.098062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:43.422535Z","time spent":"675.524536ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T05:06:44.098078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:43.673843Z","time spent":"424.227056ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T05:06:44.09798Z","caller":"traceutil/trace.go:171","msg":"trace[1877762301] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:1152; }","duration":"151.948553ms","start":"2026-05-06T05:06:43.946025Z","end":"2026-05-06T05:06:44.097974Z","steps":["trace[1877762301] 'agreement among raft nodes before linearized reading' (duration: 151.872871ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:44.484345Z","caller":"traceutil/trace.go:171","msg":"trace[1916064455] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"358.10115ms","start":"2026-05-06T05:06:44.126208Z","end":"2026-05-06T05:06:44.484309Z","steps":["trace[1916064455] 'process raft request' (duration: 303.872775ms)","trace[1916064455] 'compare' (duration: 54.078971ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:44.484453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:44.126186Z","time spent":"358.211813ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:06:56.928587Z","caller":"traceutil/trace.go:171","msg":"trace[868467847] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"132.222089ms","start":"2026-05-06T05:06:56.796331Z","end":"2026-05-06T05:06:56.928553Z","steps":["trace[868467847] 'process raft request' (duration: 128.45927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:58.780624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:06:59.281289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655377,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:06:59.30137Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.028465325s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T05:06:59.3017Z","caller":"traceutil/trace.go:171","msg":"trace[197685240] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"1.028899735s","start":"2026-05-06T05:06:58.272778Z","end":"2026-05-06T05:06:59.301678Z","steps":["trace[197685240] 'process raft request' (duration: 1.028758242s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:59.301792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:58.272751Z","time spent":"1.028987476s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:59.767515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.290312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:06:59.767826Z","caller":"traceutil/trace.go:171","msg":"trace[1281633135] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"1.007508362s","start":"2026-05-06T05:06:58.76029Z","end":"2026-05-06T05:06:59.767798Z","steps":["trace[1281633135] 'process raft request' (duration: 905.849911ms)","trace[1281633135] 'compare' (duration: 101.213351ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:59.767863Z","caller":"traceutil/trace.go:171","msg":"trace[2116585531] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1247; }","duration":"1.488192675s","start":"2026-05-06T05:06:58.279657Z","end":"2026-05-06T05:06:59.767849Z","steps":["trace[2116585531] 'read index received' (duration: 1.02191076s)","trace[2116585531] 'applied index is now lower than readState.Index' (duration: 466.280145ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:59.767858Z","caller":"traceutil/trace.go:171","msg":"trace[2070900980] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"730.482298ms","start":"2026-05-06T05:06:59.037338Z","end":"2026-05-06T05:06:59.76782Z","steps":["trace[2070900980] 'process raft request' (duration: 730.268853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:59.767935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:58.760265Z","time spent":"1.007620805s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:59.767976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:59.037309Z","time spent":"730.606281ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:59.89364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.920651ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T05:06:59.893755Z","caller":"traceutil/trace.go:171","msg":"trace[311600947] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"125.846599ms","start":"2026-05-06T05:06:59.767889Z","end":"2026-05-06T05:06:59.893736Z","steps":["trace[311600947] 'read index received' (duration: 46.241µs)","trace[311600947] 'applied index is now lower than readState.Index' (duration: 125.798678ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:59.893798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614110126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-06T05:06:59.893886Z","caller":"traceutil/trace.go:171","msg":"trace[637131930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1201; }","duration":"1.614227118s","start":"2026-05-06T05:06:58.279637Z","end":"2026-05-06T05:06:59.893864Z","steps":["trace[637131930] 'agreement among raft nodes before linearized reading' (duration: 1.488287057s)","trace[637131930] 'range keys from in-memory index tree' (duration: 125.683936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:59.893931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:58.279628Z","time spent":"1.61429233s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T05:06:59.894131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.856063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:59.89417Z","caller":"traceutil/trace.go:171","msg":"trace[867240819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"183.934505ms","start":"2026-05-06T05:06:59.710225Z","end":"2026-05-06T05:06:59.894159Z","steps":["trace[867240819] 'agreement among raft nodes before linearized reading' (duration: 183.866244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:59.894425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.695753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T05:06:59.894452Z","caller":"traceutil/trace.go:171","msg":"trace[563420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1201; }","duration":"659.752694ms","start":"2026-05-06T05:06:59.234691Z","end":"2026-05-06T05:06:59.894444Z","steps":["trace[563420] 'agreement among raft nodes before linearized reading' (duration: 659.637811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:59.894478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:59.234672Z","time spent":"659.798366ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T05:06:59.894581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.4366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T05:06:59.894613Z","caller":"traceutil/trace.go:171","msg":"trace[204110359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1201; }","duration":"933.504522ms","start":"2026-05-06T05:06:58.961101Z","end":"2026-05-06T05:06:59.894605Z","steps":["trace[204110359] 'agreement among raft nodes before linearized reading' (duration: 933.43439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:59.894634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:58.961079Z","time spent":"933.548172ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T05:06:59.894698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124004592s","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-05-06T05:06:59.894723Z","caller":"traceutil/trace.go:171","msg":"trace[1525892576] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1201; }","duration":"1.124070473s","start":"2026-05-06T05:06:58.770643Z","end":"2026-05-06T05:06:59.894714Z","steps":["trace[1525892576] 'agreement among raft nodes before linearized reading' (duration: 1.124029062s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:59.894752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:58.770623Z","time spent":"1.124117884s","remote":"127.0.0.1:50146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:06:59.894968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.599070462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-06T05:06:59.895004Z","caller":"traceutil/trace.go:171","msg":"trace[195893576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"1.599144154s","start":"2026-05-06T05:06:58.29585Z","end":"2026-05-06T05:06:59.894994Z","steps":["trace[195893576] 'agreement among raft nodes before linearized reading' (duration: 1.599046802s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:59.896898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:58.295829Z","time spent":"1.601034518s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-06T05:07:00.01242Z","caller":"traceutil/trace.go:171","msg":"trace[994896450] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"112.557077ms","start":"2026-05-06T05:06:59.899843Z","end":"2026-05-06T05:07:00.0124Z","steps":["trace[994896450] 'process raft request' (duration: 112.149297ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:00.042861Z","caller":"traceutil/trace.go:171","msg":"trace[1067116585] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"142.86586ms","start":"2026-05-06T05:06:59.899972Z","end":"2026-05-06T05:07:00.042838Z","steps":["trace[1067116585] 'process raft request' (duration: 142.705296ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:00.043614Z","caller":"traceutil/trace.go:171","msg":"trace[453891397] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"141.390075ms","start":"2026-05-06T05:06:59.902207Z","end":"2026-05-06T05:07:00.043597Z","steps":["trace[453891397] 'process raft request' (duration: 140.910794ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:00.04326Z","caller":"traceutil/trace.go:171","msg":"trace[1995318389] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"142.180594ms","start":"2026-05-06T05:06:59.901052Z","end":"2026-05-06T05:07:00.043233Z","steps":["trace[1995318389] 'process raft request' (duration: 141.736083ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:02.131618Z","caller":"traceutil/trace.go:171","msg":"trace[705997519] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"821.938458ms","start":"2026-05-06T05:07:01.309656Z","end":"2026-05-06T05:07:02.131594Z","steps":["trace[705997519] 'process raft request' (duration: 821.802735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:02.131839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:01.309639Z","time spent":"822.122342ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:02.956962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.588242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:02.957098Z","caller":"traceutil/trace.go:171","msg":"trace[1498705253] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1255; }","duration":"1.078589273s","start":"2026-05-06T05:07:01.878492Z","end":"2026-05-06T05:07:02.957081Z","steps":["trace[1498705253] 'read index received' (duration: 253.04137ms)","trace[1498705253] 'applied index is now lower than readState.Index' (duration: 825.546203ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:02.957147Z","caller":"traceutil/trace.go:171","msg":"trace[1326115048] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"1.184279528s","start":"2026-05-06T05:07:01.772837Z","end":"2026-05-06T05:07:02.957117Z","steps":["trace[1326115048] 'process raft request' (duration: 629.44675ms)","trace[1326115048] 'compare' (duration: 554.265084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:02.957211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078726006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:02.957239Z","caller":"traceutil/trace.go:171","msg":"trace[15953789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1207; }","duration":"1.078800687s","start":"2026-05-06T05:07:01.878429Z","end":"2026-05-06T05:07:02.95723Z","steps":["trace[15953789] 'agreement among raft nodes before linearized reading' (duration: 1.078741886s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:02.957274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:01.878403Z","time spent":"1.078863859s","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:07:02.957275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:01.772812Z","time spent":"1.184394011s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:02.957706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.983646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T05:07:02.95777Z","caller":"traceutil/trace.go:171","msg":"trace[1581210196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1207; }","duration":"941.083239ms","start":"2026-05-06T05:07:02.016673Z","end":"2026-05-06T05:07:02.957756Z","steps":["trace[1581210196] 'agreement among raft nodes before linearized reading' (duration: 940.868544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:02.957801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.120235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T05:07:02.957838Z","caller":"traceutil/trace.go:171","msg":"trace[1743259406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1207; }","duration":"908.195177ms","start":"2026-05-06T05:07:02.049632Z","end":"2026-05-06T05:07:02.957827Z","steps":["trace[1743259406] 'agreement among raft nodes before linearized reading' (duration: 908.063463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:02.957868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.049581Z","time spent":"908.280758ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T05:07:02.957889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.181963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T05:07:02.957917Z","caller":"traceutil/trace.go:171","msg":"trace[367882529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1207; }","duration":"910.244554ms","start":"2026-05-06T05:07:02.047665Z","end":"2026-05-06T05:07:02.95791Z","steps":["trace[367882529] 'agreement among raft nodes before linearized reading' (duration: 910.161283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:02.957942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.047644Z","time spent":"910.292625ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T05:07:02.957807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.016654Z","time spent":"941.144871ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T05:07:02.957973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.932357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:07:02.958019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.054266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T05:07:02.958055Z","caller":"traceutil/trace.go:171","msg":"trace[642099700] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1207; }","duration":"338.07506ms","start":"2026-05-06T05:07:02.619961Z","end":"2026-05-06T05:07:02.958036Z","steps":["trace[642099700] 'agreement among raft nodes before linearized reading' (duration: 337.924007ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:02.958102Z","caller":"traceutil/trace.go:171","msg":"trace[21624422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1207; }","duration":"909.17351ms","start":"2026-05-06T05:07:02.048909Z","end":"2026-05-06T05:07:02.958082Z","steps":["trace[21624422] 'agreement among raft nodes before linearized reading' (duration: 908.922824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:02.958116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.61994Z","time spent":"338.160992ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:02.958164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.048896Z","time spent":"909.241931ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T05:07:02.958005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.76784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:02.958251Z","caller":"traceutil/trace.go:171","msg":"trace[35301763] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1207; }","duration":"178.103008ms","start":"2026-05-06T05:07:02.780122Z","end":"2026-05-06T05:07:02.958225Z","steps":["trace[35301763] 'agreement among raft nodes before linearized reading' (duration: 177.700958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:03.467821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:07:03.968298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:07:04.468529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:07:04.967775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000654671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T05:07:04.967863Z","caller":"traceutil/trace.go:171","msg":"trace[207230548] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000791725s","start":"2026-05-06T05:07:02.967053Z","end":"2026-05-06T05:07:04.967845Z","steps":["trace[207230548] 'agreement among raft nodes before linearized reading' (duration: 2.000674772s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:04.967921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.967043Z","time spent":"2.000868056s","remote":"127.0.0.1:60368","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-05-06T05:07:04.968728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655396,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:07:04.996299Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.032975082s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T05:07:04.996752Z","caller":"traceutil/trace.go:171","msg":"trace[2018016637] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"2.033489253s","start":"2026-05-06T05:07:02.963209Z","end":"2026-05-06T05:07:04.996698Z","steps":["trace[2018016637] 'process raft request' (duration: 2.033251337s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:04.996954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.963184Z","time spent":"2.033660748s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:05.55469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.565914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:05.554911Z","caller":"traceutil/trace.go:171","msg":"trace[957779371] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"2.591553486s","start":"2026-05-06T05:07:02.963319Z","end":"2026-05-06T05:07:05.554873Z","steps":["trace[957779371] 'process raft request' (duration: 2.220676475s)","trace[957779371] 'compare' (duration: 370.420431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:05.55503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.963288Z","time spent":"2.591672s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:07:05.555236Z","caller":"traceutil/trace.go:171","msg":"trace[219507858] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"2.591367072s","start":"2026-05-06T05:07:02.963844Z","end":"2026-05-06T05:07:05.555211Z","steps":["trace[219507858] 'process raft request' (duration: 2.591198108s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:05.555237Z","caller":"traceutil/trace.go:171","msg":"trace[1086174021] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"2.591731351s","start":"2026-05-06T05:07:02.963454Z","end":"2026-05-06T05:07:05.555185Z","steps":["trace[1086174021] 'process raft request' (duration: 2.591368212s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:05.555344Z","caller":"traceutil/trace.go:171","msg":"trace[1147935002] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1257; }","duration":"2.588236799s","start":"2026-05-06T05:07:02.967085Z","end":"2026-05-06T05:07:05.555322Z","steps":["trace[1147935002] 'read index received' (duration: 2.029444488s)","trace[1147935002] 'applied index is now lower than readState.Index' (duration: 558.791181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:05.555426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.963819Z","time spent":"2.591498016s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:05.555428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:02.963442Z","time spent":"2.591882955s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:07:05.555483Z","caller":"traceutil/trace.go:171","msg":"trace[2002137937] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"593.715182ms","start":"2026-05-06T05:07:04.961749Z","end":"2026-05-06T05:07:05.555465Z","steps":["trace[2002137937] 'process raft request' (duration: 593.409655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:05.555575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:04.961722Z","time spent":"593.802035ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:05.55563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.740488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:07:05.555669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60852367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-06T05:07:05.555696Z","caller":"traceutil/trace.go:171","msg":"trace[997035293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1212; }","duration":"586.831411ms","start":"2026-05-06T05:07:04.968849Z","end":"2026-05-06T05:07:05.55568Z","steps":["trace[997035293] 'agreement among raft nodes before linearized reading' (duration: 586.731478ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:05.555721Z","caller":"traceutil/trace.go:171","msg":"trace[67833828] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:1212; }","duration":"1.608627892s","start":"2026-05-06T05:07:03.947079Z","end":"2026-05-06T05:07:05.555707Z","steps":["trace[67833828] 'agreement among raft nodes before linearized reading' (duration: 1.608475159s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:05.555739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:04.968836Z","time spent":"586.892432ms","remote":"127.0.0.1:60370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:07:05.555761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:03.947048Z","time spent":"1.608706734s","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"warn","ts":"2026-05-06T05:07:05.555855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418427789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T05:07:05.555919Z","caller":"traceutil/trace.go:171","msg":"trace[1997984923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1212; }","duration":"1.418552272s","start":"2026-05-06T05:07:04.137352Z","end":"2026-05-06T05:07:05.555904Z","steps":["trace[1997984923] 'agreement among raft nodes before linearized reading' (duration: 1.418362497s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:05.555969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:04.137314Z","time spent":"1.418643094s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T05:07:06.070069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.81398ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-06T05:07:06.070165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:05.556588Z","time spent":"513.573546ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-06T05:07:06.863835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.824784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:06.864103Z","caller":"traceutil/trace.go:171","msg":"trace[1331563975] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"792.914086ms","start":"2026-05-06T05:07:06.07117Z","end":"2026-05-06T05:07:06.864084Z","steps":["trace[1331563975] 'process raft request' (duration: 792.869685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:06.86418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:06.071147Z","time spent":"792.991178ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:07:06.864253Z","caller":"traceutil/trace.go:171","msg":"trace[1212712650] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.303355718s","start":"2026-05-06T05:07:05.56087Z","end":"2026-05-06T05:07:06.864225Z","steps":["trace[1212712650] 'process raft request' (duration: 800.071103ms)","trace[1212712650] 'compare' (duration: 502.588569ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:06.864326Z","caller":"traceutil/trace.go:171","msg":"trace[1743745670] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1262; }","duration":"1.299561389s","start":"2026-05-06T05:07:05.564747Z","end":"2026-05-06T05:07:06.864308Z","steps":["trace[1743745670] 'read index received' (duration: 168.512612ms)","trace[1743745670] 'applied index is now lower than readState.Index' (duration: 1.131046977s)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:06.864336Z","caller":"traceutil/trace.go:171","msg":"trace[962180595] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"1.30002827s","start":"2026-05-06T05:07:05.564289Z","end":"2026-05-06T05:07:06.864317Z","steps":["trace[962180595] 'process raft request' (duration: 1.299645981s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:06.864367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:05.560851Z","time spent":"1.303461751s","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T05:07:06.864428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:05.56428Z","time spent":"1.300097262s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:06.864441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299692883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:06.864479Z","caller":"traceutil/trace.go:171","msg":"trace[275315102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"1.299734334s","start":"2026-05-06T05:07:05.564735Z","end":"2026-05-06T05:07:06.864469Z","steps":["trace[275315102] 'agreement among raft nodes before linearized reading' (duration: 1.299667222s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:06.864507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:05.564707Z","time spent":"1.299795055s","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:07:06.86449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.861745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:07:06.864502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117041997s","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-05-06T05:07:06.864649Z","caller":"traceutil/trace.go:171","msg":"trace[1295630377] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1215; }","duration":"1.117249743s","start":"2026-05-06T05:07:05.747377Z","end":"2026-05-06T05:07:06.864626Z","steps":["trace[1295630377] 'agreement among raft nodes before linearized reading' (duration: 1.117050618s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:06.864709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:05.747353Z","time spent":"1.117339354s","remote":"127.0.0.1:50024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:06.864716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.159702ms","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":"warn","ts":"2026-05-06T05:07:06.86474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.016599ms","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-05-06T05:07:06.864745Z","caller":"traceutil/trace.go:171","msg":"trace[357772783] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1215; }","duration":"671.227394ms","start":"2026-05-06T05:07:06.193511Z","end":"2026-05-06T05:07:06.864738Z","steps":["trace[357772783] 'agreement among raft nodes before linearized reading' (duration: 671.109471ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:06.864599Z","caller":"traceutil/trace.go:171","msg":"trace[903660914] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1215; }","duration":"789.991898ms","start":"2026-05-06T05:07:06.074595Z","end":"2026-05-06T05:07:06.864587Z","steps":["trace[903660914] 'agreement among raft nodes before linearized reading' (duration: 789.855854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:06.86479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:06.193487Z","time spent":"671.291516ms","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":103,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:07:06.86478Z","caller":"traceutil/trace.go:171","msg":"trace[271187456] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1215; }","duration":"961.097011ms","start":"2026-05-06T05:07:05.903673Z","end":"2026-05-06T05:07:06.86477Z","steps":["trace[271187456] 'agreement among raft nodes before linearized reading' (duration: 961.032819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:06.864825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:05.903652Z","time spent":"961.164893ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:06.864808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:06.074586Z","time spent":"790.201992ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:07.532938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.093839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-06T05:07:07.533038Z","caller":"traceutil/trace.go:171","msg":"trace[2041063215] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"531.225253ms","start":"2026-05-06T05:07:07.001796Z","end":"2026-05-06T05:07:07.533021Z","steps":["trace[2041063215] 'range keys from in-memory index tree' (duration: 530.978877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:07.533041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.663461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-06T05:07:07.533077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.001776Z","time spent":"531.291764ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-06T05:07:07.5331Z","caller":"traceutil/trace.go:171","msg":"trace[475651078] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1266; }","duration":"410.982615ms","start":"2026-05-06T05:07:07.122106Z","end":"2026-05-06T05:07:07.533088Z","steps":["trace[475651078] 'read index received' (duration: 269.17819ms)","trace[475651078] 'applied index is now lower than readState.Index' (duration: 141.803215ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:07.533197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.094258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:07:07.533162Z","caller":"traceutil/trace.go:171","msg":"trace[1892027339] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"441.192036ms","start":"2026-05-06T05:07:07.091916Z","end":"2026-05-06T05:07:07.533108Z","steps":["trace[1892027339] 'process raft request' (duration: 299.383111ms)","trace[1892027339] 'compare' (duration: 141.499728ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:07.533224Z","caller":"traceutil/trace.go:171","msg":"trace[2067398963] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1216; }","duration":"411.158689ms","start":"2026-05-06T05:07:07.122057Z","end":"2026-05-06T05:07:07.533216Z","steps":["trace[2067398963] 'agreement among raft nodes before linearized reading' (duration: 411.100798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:07.533752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.122035Z","time spent":"411.686241ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:07.533963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.09189Z","time spent":"441.559404ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:08.702224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020592091s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:08.702421Z","caller":"traceutil/trace.go:171","msg":"trace[1633064878] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"1.165053387s","start":"2026-05-06T05:07:07.537345Z","end":"2026-05-06T05:07:08.702398Z","steps":["trace[1633064878] 'process raft request' (duration: 144.186531ms)","trace[1633064878] 'compare' (duration: 1.01976719s)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:08.702466Z","caller":"traceutil/trace.go:171","msg":"trace[1333611677] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"1.141191015s","start":"2026-05-06T05:07:07.561251Z","end":"2026-05-06T05:07:08.702442Z","steps":["trace[1333611677] 'process raft request' (duration: 1.141086633s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:08.702515Z","caller":"traceutil/trace.go:171","msg":"trace[509256565] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"1.142993928s","start":"2026-05-06T05:07:07.559494Z","end":"2026-05-06T05:07:08.702488Z","steps":["trace[509256565] 'read index received' (duration: 121.866786ms)","trace[509256565] 'applied index is now lower than readState.Index' (duration: 1.021124612s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:08.70254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.537322Z","time spent":"1.165145099s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:08.702552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.56122Z","time spent":"1.141289178s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:08.702682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143186503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T05:07:08.702713Z","caller":"traceutil/trace.go:171","msg":"trace[2001187580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1218; }","duration":"1.143272824s","start":"2026-05-06T05:07:07.559432Z","end":"2026-05-06T05:07:08.702705Z","steps":["trace[2001187580] 'agreement among raft nodes before linearized reading' (duration: 1.143174532s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:08.702706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.951501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T05:07:08.702734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.559408Z","time spent":"1.143321266s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T05:07:08.702746Z","caller":"traceutil/trace.go:171","msg":"trace[1277119420] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1218; }","duration":"709.046643ms","start":"2026-05-06T05:07:07.993689Z","end":"2026-05-06T05:07:08.702736Z","steps":["trace[1277119420] 'agreement among raft nodes before linearized reading' (duration: 708.941551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:08.702752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.389215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:07:08.702785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.993671Z","time spent":"709.105644ms","remote":"127.0.0.1:60446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:07:08.702788Z","caller":"traceutil/trace.go:171","msg":"trace[323888038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"824.451527ms","start":"2026-05-06T05:07:07.878325Z","end":"2026-05-06T05:07:08.702777Z","steps":["trace[323888038] 'agreement among raft nodes before linearized reading' (duration: 824.398666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:08.702817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143053059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-06T05:07:08.702801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143210643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-06T05:07:08.702835Z","caller":"traceutil/trace.go:171","msg":"trace[1268708157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1218; }","duration":"1.14307722s","start":"2026-05-06T05:07:07.559751Z","end":"2026-05-06T05:07:08.702828Z","steps":["trace[1268708157] 'agreement among raft nodes before linearized reading' (duration: 1.143031419s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:08.702856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.559746Z","time spent":"1.143106281s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T05:07:08.702872Z","caller":"traceutil/trace.go:171","msg":"trace[261542301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"1.143315516s","start":"2026-05-06T05:07:07.559538Z","end":"2026-05-06T05:07:08.702854Z","steps":["trace[261542301] 'agreement among raft nodes before linearized reading' (duration: 1.143095751s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:08.702921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.559533Z","time spent":"1.143380597s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T05:07:08.702828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:07.878308Z","time spent":"824.510339ms","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:07:09.36992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:07:09.870762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:07:09.887289Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.17998795s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T05:07:10.503299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.652788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:10.503516Z","caller":"traceutil/trace.go:171","msg":"trace[21177849] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"1.796299553s","start":"2026-05-06T05:07:08.707187Z","end":"2026-05-06T05:07:10.503487Z","steps":["trace[21177849] 'process raft request' (duration: 1.180301137s)","trace[21177849] 'compare' (duration: 614.946682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:10.503657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:08.707163Z","time spent":"1.796442457s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:11.712443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000001449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T05:07:11.712545Z","caller":"traceutil/trace.go:171","msg":"trace[177555288] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000153452s","start":"2026-05-06T05:07:09.712374Z","end":"2026-05-06T05:07:11.712528Z","steps":["trace[177555288] 'agreement among raft nodes before linearized reading' (duration: 2.000034729s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:11.712588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:09.712354Z","time spent":"2.000224744s","remote":"127.0.0.1:60368","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-05-06T05:07:11.715959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101715214s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:11.716545Z","caller":"traceutil/trace.go:171","msg":"trace[1597446271] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"3.007908531s","start":"2026-05-06T05:07:08.708608Z","end":"2026-05-06T05:07:11.716516Z","steps":["trace[1597446271] 'process raft request' (duration: 3.007452011s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:11.716595Z","caller":"traceutil/trace.go:171","msg":"trace[274936828] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"3.009180781s","start":"2026-05-06T05:07:08.707323Z","end":"2026-05-06T05:07:11.716504Z","steps":["trace[274936828] 'process raft request' (duration: 1.906844042s)","trace[274936828] 'compare' (duration: 1.101594442s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:11.716698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:08.708598Z","time spent":"3.008011243s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:11.71685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:08.707306Z","time spent":"3.009462477s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:11.878189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999841996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-06T05:07:11.87829Z","caller":"traceutil/trace.go:171","msg":"trace[86079548] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999997918s","start":"2026-05-06T05:07:09.878272Z","end":"2026-05-06T05:07:11.87827Z","steps":["trace[86079548] 'agreement among raft nodes before linearized reading' (duration: 1.999874226s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:11.878338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:09.878251Z","time spent":"2.000075001s","remote":"127.0.0.1:60370","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-05-06T05:07:12.50735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.562527ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:12.507501Z","caller":"traceutil/trace.go:171","msg":"trace[1075905588] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1221; }","duration":"587.725931ms","start":"2026-05-06T05:07:11.919757Z","end":"2026-05-06T05:07:12.507483Z","steps":["trace[1075905588] 'range keys from in-memory index tree' (duration: 587.544557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.507663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.146445ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T05:07:12.50791Z","caller":"traceutil/trace.go:171","msg":"trace[226630909] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"789.502057ms","start":"2026-05-06T05:07:11.718389Z","end":"2026-05-06T05:07:12.507891Z","steps":["trace[226630909] 'process raft request' (duration: 789.456846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.507989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:11.718362Z","time spent":"789.582689ms","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:07:12.508016Z","caller":"traceutil/trace.go:171","msg":"trace[434121340] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"2.690004185s","start":"2026-05-06T05:07:09.817994Z","end":"2026-05-06T05:07:12.507998Z","steps":["trace[434121340] 'process raft request' (duration: 2.689745159s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:12.508038Z","caller":"traceutil/trace.go:171","msg":"trace[699577233] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1269; }","duration":"3.639144825s","start":"2026-05-06T05:07:08.86888Z","end":"2026-05-06T05:07:12.508025Z","steps":["trace[699577233] 'read index received' (duration: 1.018599354s)","trace[699577233] 'applied index is now lower than readState.Index' (duration: 2.620543751s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:12.508128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:09.817969Z","time spent":"2.690084267s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:12.508245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.63936802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-06T05:07:12.508272Z","caller":"traceutil/trace.go:171","msg":"trace[139634486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"3.639434622s","start":"2026-05-06T05:07:08.868829Z","end":"2026-05-06T05:07:12.508264Z","steps":["trace[139634486] 'agreement among raft nodes before linearized reading' (duration: 3.639275058s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.508299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:08.868808Z","time spent":"3.639484993s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T05:07:12.50833Z","caller":"traceutil/trace.go:171","msg":"trace[1058742766] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"1.799654027s","start":"2026-05-06T05:07:10.708662Z","end":"2026-05-06T05:07:12.508316Z","steps":["trace[1058742766] 'process raft request' (duration: 1.799160396s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.508451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.514053453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:12.508484Z","caller":"traceutil/trace.go:171","msg":"trace[770831146] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1224; }","duration":"3.514122765s","start":"2026-05-06T05:07:08.99435Z","end":"2026-05-06T05:07:12.508473Z","steps":["trace[770831146] 'agreement among raft nodes before linearized reading' (duration: 3.514069644s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.508512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:08.994331Z","time spent":"3.514173297s","remote":"127.0.0.1:50178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:12.508544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.919961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:12.508576Z","caller":"traceutil/trace.go:171","msg":"trace[900619210] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1224; }","duration":"239.984503ms","start":"2026-05-06T05:07:12.268582Z","end":"2026-05-06T05:07:12.508567Z","steps":["trace[900619210] 'agreement among raft nodes before linearized reading' (duration: 239.926092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.508658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.496749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:12.508687Z","caller":"traceutil/trace.go:171","msg":"trace[284916948] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1224; }","duration":"838.561141ms","start":"2026-05-06T05:07:11.670118Z","end":"2026-05-06T05:07:12.508679Z","steps":["trace[284916948] 'agreement among raft nodes before linearized reading' (duration: 838.51273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.508457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:10.708651Z","time spent":"1.799710338s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:12.508697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.720221669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:07:12.508711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:11.670101Z","time spent":"838.602791ms","remote":"127.0.0.1:60604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:07:12.508729Z","caller":"traceutil/trace.go:171","msg":"trace[1359430765] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1224; }","duration":"1.720297081s","start":"2026-05-06T05:07:10.788423Z","end":"2026-05-06T05:07:12.50872Z","steps":["trace[1359430765] 'agreement among raft nodes before linearized reading' (duration: 1.720240079s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.508712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.436084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:07:12.508757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:10.788401Z","time spent":"1.720347071s","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:07:12.50878Z","caller":"traceutil/trace.go:171","msg":"trace[107154120] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1224; }","duration":"220.544316ms","start":"2026-05-06T05:07:12.288215Z","end":"2026-05-06T05:07:12.508759Z","steps":["trace[107154120] 'agreement among raft nodes before linearized reading' (duration: 220.421823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.508875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.180154ms","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-05-06T05:07:12.508907Z","caller":"traceutil/trace.go:171","msg":"trace[258596738] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1224; }","duration":"512.251886ms","start":"2026-05-06T05:07:11.996646Z","end":"2026-05-06T05:07:12.508898Z","steps":["trace[258596738] 'agreement among raft nodes before linearized reading' (duration: 512.188954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.508923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.800768473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-06T05:07:12.508932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:11.996623Z","time spent":"512.301857ms","remote":"127.0.0.1:60550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:07:12.508949Z","caller":"traceutil/trace.go:171","msg":"trace[1006899424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"1.800795344s","start":"2026-05-06T05:07:10.708146Z","end":"2026-05-06T05:07:12.508941Z","steps":["trace[1006899424] 'agreement among raft nodes before linearized reading' (duration: 1.800717692s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.508976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:10.708091Z","time spent":"1.800877066s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T05:07:12.50911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.126904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:12.509138Z","caller":"traceutil/trace.go:171","msg":"trace[1234850203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"623.180294ms","start":"2026-05-06T05:07:11.88595Z","end":"2026-05-06T05:07:12.50913Z","steps":["trace[1234850203] 'agreement among raft nodes before linearized reading' (duration: 623.134873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:12.509166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:11.885936Z","time spent":"623.223616ms","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:07:13.1648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.206878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:13.165019Z","caller":"traceutil/trace.go:171","msg":"trace[1872689370] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"651.91244ms","start":"2026-05-06T05:07:12.513071Z","end":"2026-05-06T05:07:13.164984Z","steps":["trace[1872689370] 'read index received' (duration: 246.19987ms)","trace[1872689370] 'applied index is now lower than readState.Index' (duration: 405.71089ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:13.165089Z","caller":"traceutil/trace.go:171","msg":"trace[1383531624] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"652.141394ms","start":"2026-05-06T05:07:12.512911Z","end":"2026-05-06T05:07:13.165052Z","steps":["trace[1383531624] 'process raft request' (duration: 246.379643ms)","trace[1383531624] 'compare' (duration: 405.042684ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:13.165224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.144035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ace19eea56f55f\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-05-06T05:07:13.16527Z","caller":"traceutil/trace.go:171","msg":"trace[1193956813] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18ace19eea56f55f; range_end:; response_count:1; response_revision:1225; }","duration":"652.196236ms","start":"2026-05-06T05:07:12.513057Z","end":"2026-05-06T05:07:13.165253Z","steps":["trace[1193956813] 'agreement among raft nodes before linearized reading' (duration: 652.007702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:13.165269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:12.512899Z","time spent":"652.271798ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:13.165312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:12.513052Z","time spent":"652.250867ms","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ace19eea56f55f\" "} {"level":"warn","ts":"2026-05-06T05:07:13.767848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.26089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:13.768091Z","caller":"traceutil/trace.go:171","msg":"trace[1402341358] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"1.25366401s","start":"2026-05-06T05:07:12.514394Z","end":"2026-05-06T05:07:13.768058Z","steps":["trace[1402341358] 'process raft request' (duration: 817.075004ms)","trace[1402341358] 'compare' (duration: 436.132186ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:13.768267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:12.514374Z","time spent":"1.253790353s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:07:13.768119Z","caller":"traceutil/trace.go:171","msg":"trace[901479329] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1277; }","duration":"558.285088ms","start":"2026-05-06T05:07:13.209813Z","end":"2026-05-06T05:07:13.768098Z","steps":["trace[901479329] 'read index received' (duration: 121.832115ms)","trace[901479329] 'applied index is now lower than readState.Index' (duration: 436.451833ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:13.768565Z","caller":"traceutil/trace.go:171","msg":"trace[177605585] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"596.353144ms","start":"2026-05-06T05:07:13.172192Z","end":"2026-05-06T05:07:13.768545Z","steps":["trace[177605585] 'process raft request' (duration: 595.848992ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:13.768543Z","caller":"traceutil/trace.go:171","msg":"trace[973799857] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"1.253894316s","start":"2026-05-06T05:07:12.514508Z","end":"2026-05-06T05:07:13.768403Z","steps":["trace[973799857] 'process raft request' (duration: 1.253459505s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:13.768663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:13.172164Z","time spent":"596.447637ms","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:13.768799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:12.5145Z","time spent":"1.254154312s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:13.769047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.22724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:13.769108Z","caller":"traceutil/trace.go:171","msg":"trace[91650094] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1228; }","duration":"559.323983ms","start":"2026-05-06T05:07:13.209769Z","end":"2026-05-06T05:07:13.769093Z","steps":["trace[91650094] 'agreement among raft nodes before linearized reading' (duration: 558.379811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:13.769153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:13.209748Z","time spent":"559.395435ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:15.171542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.307576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:15.171663Z","caller":"traceutil/trace.go:171","msg":"trace[2101181398] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"1.398725549s","start":"2026-05-06T05:07:13.772918Z","end":"2026-05-06T05:07:15.171644Z","steps":["trace[2101181398] 'process raft request' (duration: 470.214428ms)","trace[2101181398] 'compare' (duration: 927.760333ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:15.171734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:13.772897Z","time spent":"1.398795891s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:15.87861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999683892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T05:07:15.878712Z","caller":"traceutil/trace.go:171","msg":"trace[673631638] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999873676s","start":"2026-05-06T05:07:13.878818Z","end":"2026-05-06T05:07:15.878692Z","steps":["trace[673631638] 'agreement among raft nodes before linearized reading' (duration: 1.999738273s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:15.878789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:13.878776Z","time spent":"1.999969749s","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 05:07:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T05:07:15.909247Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.666151658s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T05:07:15.909575Z","caller":"traceutil/trace.go:171","msg":"trace[1630825590] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1280; }","duration":"2.0306494s","start":"2026-05-06T05:07:13.878901Z","end":"2026-05-06T05:07:15.909551Z","steps":["trace[1630825590] 'read index received' (duration: 364.242796ms)","trace[1630825590] 'applied index is now lower than readState.Index' (duration: 1.666405554s)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:15.909682Z","caller":"traceutil/trace.go:171","msg":"trace[752755420] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"2.136729945s","start":"2026-05-06T05:07:13.772942Z","end":"2026-05-06T05:07:15.909671Z","steps":["trace[752755420] 'process raft request' (duration: 2.136474039s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:15.90979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:13.772918Z","time spent":"2.136795796s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:17.183384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000708072s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T05:07:17.183472Z","caller":"traceutil/trace.go:171","msg":"trace[608012462] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000838115s","start":"2026-05-06T05:07:15.182617Z","end":"2026-05-06T05:07:17.183456Z","steps":["trace[608012462] 'agreement among raft nodes before linearized reading' (duration: 2.000725502s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:17.183515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:15.182605Z","time spent":"2.000898617s","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 05:07:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T05:07:17.210567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.692834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:17.210716Z","caller":"traceutil/trace.go:171","msg":"trace[1058052989] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1282; }","duration":"1.301057398s","start":"2026-05-06T05:07:15.909643Z","end":"2026-05-06T05:07:17.2107Z","steps":["trace[1058052989] 'read index received' (duration: 368.108938ms)","trace[1058052989] 'applied index is now lower than readState.Index' (duration: 932.94764ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:17.210789Z","caller":"traceutil/trace.go:171","msg":"trace[1857188029] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"2.696324974s","start":"2026-05-06T05:07:14.514456Z","end":"2026-05-06T05:07:17.210781Z","steps":["trace[1857188029] 'process raft request' (duration: 1.763345453s)","trace[1857188029] 'compare' (duration: 932.585762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:17.210847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:14.514435Z","time spent":"2.696376746s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:17.211062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12555235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:07:17.211146Z","caller":"traceutil/trace.go:171","msg":"trace[1034456189] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1232; }","duration":"1.125681953s","start":"2026-05-06T05:07:16.085444Z","end":"2026-05-06T05:07:17.211126Z","steps":["trace[1034456189] 'agreement among raft nodes before linearized reading' (duration: 1.125518599s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:17.211167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.263456973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-06T05:07:17.211157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041481051s","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-05-06T05:07:17.211204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:16.085424Z","time spent":"1.125765146s","remote":"127.0.0.1:60692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:07:17.211241Z","caller":"traceutil/trace.go:171","msg":"trace[1676867379] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:1232; }","duration":"3.263570625s","start":"2026-05-06T05:07:13.947654Z","end":"2026-05-06T05:07:17.211224Z","steps":["trace[1676867379] 'agreement among raft nodes before linearized reading' (duration: 3.263266468s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:17.211259Z","caller":"traceutil/trace.go:171","msg":"trace[1625669079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1232; }","duration":"2.041677915s","start":"2026-05-06T05:07:15.169557Z","end":"2026-05-06T05:07:17.211235Z","steps":["trace[1625669079] 'agreement among raft nodes before linearized reading' (duration: 2.041436139s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:17.211804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436435131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-06T05:07:17.211801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:15.169536Z","time spent":"2.042247519s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T05:07:17.211847Z","caller":"traceutil/trace.go:171","msg":"trace[1393684909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1232; }","duration":"1.436536274s","start":"2026-05-06T05:07:15.775302Z","end":"2026-05-06T05:07:17.211838Z","steps":["trace[1393684909] 'agreement among raft nodes before linearized reading' (duration: 1.435573491s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:17.211875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:15.775277Z","time spent":"1.436593235s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T05:07:17.21139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.448669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:17.21195Z","caller":"traceutil/trace.go:171","msg":"trace[1331655145] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1232; }","duration":"370.063992ms","start":"2026-05-06T05:07:16.841879Z","end":"2026-05-06T05:07:17.211943Z","steps":["trace[1331655145] 'agreement among raft nodes before linearized reading' (duration: 369.452058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:17.21197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:16.841857Z","time spent":"370.105994ms","remote":"127.0.0.1:60590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:17.211292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:13.947629Z","time spent":"3.263651238s","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"info","ts":"2026-05-06T05:07:17.211444Z","caller":"traceutil/trace.go:171","msg":"trace[1750130823] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"1.913332617s","start":"2026-05-06T05:07:15.298054Z","end":"2026-05-06T05:07:17.211386Z","steps":["trace[1750130823] 'process raft request' (duration: 1.9125974s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:17.212089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:15.298026Z","time spent":"1.914045053s","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T05:07:17.211371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347311416s","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-05-06T05:07:17.211563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435088679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T05:07:17.212201Z","caller":"traceutil/trace.go:171","msg":"trace[1064138879] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1232; }","duration":"1.348187676s","start":"2026-05-06T05:07:15.863997Z","end":"2026-05-06T05:07:17.212185Z","steps":["trace[1064138879] 'agreement among raft nodes before linearized reading' (duration: 1.347337236s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:17.211491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332703882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:17.212219Z","caller":"traceutil/trace.go:171","msg":"trace[482014129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"1.435830247s","start":"2026-05-06T05:07:15.77638Z","end":"2026-05-06T05:07:17.212211Z","steps":["trace[482014129] 'agreement among raft nodes before linearized reading' (duration: 1.435092419s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:17.212301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:15.776351Z","time spent":"1.43594325s","remote":"127.0.0.1:60638","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":"info","ts":"2026-05-06T05:07:17.21231Z","caller":"traceutil/trace.go:171","msg":"trace[1673371565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1232; }","duration":"1.333552503s","start":"2026-05-06T05:07:15.878738Z","end":"2026-05-06T05:07:17.212291Z","steps":["trace[1673371565] 'agreement among raft nodes before linearized reading' (duration: 1.332343714s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:17.21237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:15.878723Z","time spent":"1.333630964s","remote":"127.0.0.1:60370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:07:17.212261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:15.863969Z","time spent":"1.348274768s","remote":"127.0.0.1:50084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:17.718464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655449,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:07:18.443263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.512382ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-06T05:07:18.443367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.213494Z","time spent":"1.229869483s","remote":"127.0.0.1:60408","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-06T05:07:19.221675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000991299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-06T05:07:19.221746Z","caller":"traceutil/trace.go:171","msg":"trace[619425362] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001082031s","start":"2026-05-06T05:07:17.220642Z","end":"2026-05-06T05:07:19.221724Z","steps":["trace[619425362] 'agreement among raft nodes before linearized reading' (duration: 2.001001969s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:19.221782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.220635Z","time spent":"2.001140172s","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/06 05:07:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-06T05:07:19.86966Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.066650123s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T05:07:20.91163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041684707s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:20.91258Z","caller":"traceutil/trace.go:171","msg":"trace[1354365602] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"3.695472108s","start":"2026-05-06T05:07:17.217093Z","end":"2026-05-06T05:07:20.912565Z","steps":["trace[1354365602] 'process raft request' (duration: 3.695422137s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:20.912582Z","caller":"traceutil/trace.go:171","msg":"trace[525409991] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"3.695599152s","start":"2026-05-06T05:07:17.216955Z","end":"2026-05-06T05:07:20.912554Z","steps":["trace[525409991] 'process raft request' (duration: 3.695463669s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.912716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.217088Z","time spent":"3.69556323s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:20.912749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.216943Z","time spent":"3.695729735s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:07:20.912829Z","caller":"traceutil/trace.go:171","msg":"trace[152946376] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"3.212396387s","start":"2026-05-06T05:07:17.70042Z","end":"2026-05-06T05:07:20.912817Z","steps":["trace[152946376] 'process raft request' (duration: 3.212118711s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:20.912581Z","caller":"traceutil/trace.go:171","msg":"trace[1294038112] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"3.695514839s","start":"2026-05-06T05:07:17.217046Z","end":"2026-05-06T05:07:20.912561Z","steps":["trace[1294038112] 'process raft request' (duration: 3.695446388s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:20.91258Z","caller":"traceutil/trace.go:171","msg":"trace[895040097] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"3.696556914s","start":"2026-05-06T05:07:17.215991Z","end":"2026-05-06T05:07:20.912548Z","steps":["trace[895040097] 'process raft request' (duration: 2.653892474s)","trace[895040097] 'compare' (duration: 1.040263693s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:20.912952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.700394Z","time spent":"3.21248842s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:07:20.912964Z","caller":"traceutil/trace.go:171","msg":"trace[489465235] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"1.695159006s","start":"2026-05-06T05:07:19.217782Z","end":"2026-05-06T05:07:20.912941Z","steps":["trace[489465235] 'process raft request' (duration: 1.695115005s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.913063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.215967Z","time spent":"3.697024975s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:20.913103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:19.217754Z","time spent":"1.695281308s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:20.913145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.695319915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ace19eea56f55f\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-05-06T05:07:20.913185Z","caller":"traceutil/trace.go:171","msg":"trace[797853265] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18ace19eea56f55f; range_end:; response_count:1; response_revision:1239; }","duration":"3.695375457s","start":"2026-05-06T05:07:17.217797Z","end":"2026-05-06T05:07:20.913172Z","steps":["trace[797853265] 'agreement among raft nodes before linearized reading' (duration: 3.695224203s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.913223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.217787Z","time spent":"3.695426318s","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ace19eea56f55f\" "} {"level":"info","ts":"2026-05-06T05:07:20.913245Z","caller":"traceutil/trace.go:171","msg":"trace[869568884] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"2.468485504s","start":"2026-05-06T05:07:18.444736Z","end":"2026-05-06T05:07:20.913222Z","steps":["trace[869568884] 'process raft request' (duration: 2.468071494s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:20.913001Z","caller":"traceutil/trace.go:171","msg":"trace[1990526800] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1284; }","duration":"3.695162941s","start":"2026-05-06T05:07:17.217826Z","end":"2026-05-06T05:07:20.912989Z","steps":["trace[1990526800] 'read index received' (duration: 585.033729ms)","trace[1990526800] 'applied index is now lower than readState.Index' (duration: 3.110127822s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:20.913296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:18.444705Z","time spent":"2.468562965s","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:20.912947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.217038Z","time spent":"3.695878069s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:20.913425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152402591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T05:07:20.913445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.372186199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:07:20.913463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.979018953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:07:20.913473Z","caller":"traceutil/trace.go:171","msg":"trace[711693876] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1239; }","duration":"1.152493474s","start":"2026-05-06T05:07:19.76097Z","end":"2026-05-06T05:07:20.913463Z","steps":["trace[711693876] 'agreement among raft nodes before linearized reading' (duration: 1.152418692s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:20.913497Z","caller":"traceutil/trace.go:171","msg":"trace[36823550] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1239; }","duration":"2.372285901s","start":"2026-05-06T05:07:18.5412Z","end":"2026-05-06T05:07:20.913486Z","steps":["trace[36823550] 'agreement among raft nodes before linearized reading' (duration: 2.372209879s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.913541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200691537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:07:20.913549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:18.541175Z","time spent":"2.372364292s","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:20.913541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.999210833s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T05:07:20.913575Z","caller":"traceutil/trace.go:171","msg":"trace[539161946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"1.200764009s","start":"2026-05-06T05:07:19.712803Z","end":"2026-05-06T05:07:20.913567Z","steps":["trace[539161946] 'agreement among raft nodes before linearized reading' (duration: 1.200715978s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.913579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.691565842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:20.913637Z","caller":"traceutil/trace.go:171","msg":"trace[2011899139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1239; }","duration":"2.999352456s","start":"2026-05-06T05:07:17.914274Z","end":"2026-05-06T05:07:20.913626Z","steps":["trace[2011899139] 'agreement among raft nodes before linearized reading' (duration: 2.999180952s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.913638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:19.712783Z","time spent":"1.200848771s","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:07:20.91364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:19.760948Z","time spent":"1.152606656s","remote":"127.0.0.1:54590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:20.913914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.613442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:20.913971Z","caller":"traceutil/trace.go:171","msg":"trace[1681255952] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1239; }","duration":"525.703534ms","start":"2026-05-06T05:07:20.388255Z","end":"2026-05-06T05:07:20.913959Z","steps":["trace[1681255952] 'agreement among raft nodes before linearized reading' (duration: 525.633043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.914023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:20.388235Z","time spent":"525.778216ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:20.914066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073355752s","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-05-06T05:07:20.914144Z","caller":"traceutil/trace.go:171","msg":"trace[1381473517] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1239; }","duration":"1.073447074s","start":"2026-05-06T05:07:19.840678Z","end":"2026-05-06T05:07:20.914125Z","steps":["trace[1381473517] 'agreement among raft nodes before linearized reading' (duration: 1.073322841s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.914251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:19.840616Z","time spent":"1.073614018s","remote":"127.0.0.1:50180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:20.914532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135623596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:07:20.914609Z","caller":"traceutil/trace.go:171","msg":"trace[1811031068] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1239; }","duration":"1.135725379s","start":"2026-05-06T05:07:19.77886Z","end":"2026-05-06T05:07:20.914586Z","steps":["trace[1811031068] 'agreement among raft nodes before linearized reading' (duration: 1.135623057s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.914679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:19.778844Z","time spent":"1.135813851s","remote":"127.0.0.1:60798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:07:20.914873Z","caller":"traceutil/trace.go:171","msg":"trace[1320108076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"1.691697084s","start":"2026-05-06T05:07:19.221963Z","end":"2026-05-06T05:07:20.91366Z","steps":["trace[1320108076] 'agreement among raft nodes before linearized reading' (duration: 1.691576851s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.914921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:19.221942Z","time spent":"1.692968255s","remote":"127.0.0.1:60370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T05:07:20.9135Z","caller":"traceutil/trace.go:171","msg":"trace[2014612408] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1239; }","duration":"1.979093794s","start":"2026-05-06T05:07:18.934395Z","end":"2026-05-06T05:07:20.913489Z","steps":["trace[2014612408] 'agreement among raft nodes before linearized reading' (duration: 1.979006882s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.915216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:18.934374Z","time spent":"1.980828725s","remote":"127.0.0.1:60574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:20.913433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.593304907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:07:20.91551Z","caller":"traceutil/trace.go:171","msg":"trace[99795903] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1239; }","duration":"3.595407436s","start":"2026-05-06T05:07:17.320085Z","end":"2026-05-06T05:07:20.915492Z","steps":["trace[99795903] 'agreement among raft nodes before linearized reading' (duration: 3.593281456s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.915553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.320071Z","time spent":"3.595466657s","remote":"127.0.0.1:54600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:20.916685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:17.914247Z","time spent":"2.999418138s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T05:07:21.498986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.891643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:21.499078Z","caller":"traceutil/trace.go:171","msg":"trace[1423498847] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1292; }","duration":"580.679235ms","start":"2026-05-06T05:07:20.918382Z","end":"2026-05-06T05:07:21.499061Z","steps":["trace[1423498847] 'read index received' (duration: 243.651219ms)","trace[1423498847] 'applied index is now lower than readState.Index' (duration: 337.026896ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:21.499132Z","caller":"traceutil/trace.go:171","msg":"trace[2073521273] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"582.087427ms","start":"2026-05-06T05:07:20.916996Z","end":"2026-05-06T05:07:21.499083Z","steps":["trace[2073521273] 'process raft request' (duration: 245.017421ms)","trace[2073521273] 'compare' (duration: 336.464852ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:21.499281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.872319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-05-06T05:07:21.499322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:20.916978Z","time spent":"582.244182ms","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:07:21.499377Z","caller":"traceutil/trace.go:171","msg":"trace[1716265611] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1240; }","duration":"581.002873ms","start":"2026-05-06T05:07:20.918352Z","end":"2026-05-06T05:07:21.499355Z","steps":["trace[1716265611] 'agreement among raft nodes before linearized reading' (duration: 580.746057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:21.499457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:20.918338Z","time spent":"581.090164ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-06T05:07:21.999878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224447241655464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:07:22.122282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.722668ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:22.122415Z","caller":"traceutil/trace.go:171","msg":"trace[859654330] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1240; }","duration":"202.877652ms","start":"2026-05-06T05:07:21.919518Z","end":"2026-05-06T05:07:22.122396Z","steps":["trace[859654330] 'range keys from in-memory index tree' (duration: 202.679126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:22.122737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.301064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:22.123175Z","caller":"traceutil/trace.go:171","msg":"trace[1147158188] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"1.202013808s","start":"2026-05-06T05:07:20.921066Z","end":"2026-05-06T05:07:22.12308Z","steps":["trace[1147158188] 'process raft request' (duration: 1.094289284s)","trace[1147158188] 'compare' (duration: 106.946415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:22.123323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:20.921051Z","time spent":"1.202190272s","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:22.555667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.811317ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T05:07:22.555793Z","caller":"traceutil/trace.go:171","msg":"trace[126888759] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1293; }","duration":"1.05667223s","start":"2026-05-06T05:07:21.499098Z","end":"2026-05-06T05:07:22.55577Z","steps":["trace[126888759] 'read index received' (duration: 516.254491ms)","trace[126888759] 'applied index is now lower than readState.Index' (duration: 540.415829ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:22.556015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.632663225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:22.556059Z","caller":"traceutil/trace.go:171","msg":"trace[2107313801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"1.632737646s","start":"2026-05-06T05:07:20.923307Z","end":"2026-05-06T05:07:22.556044Z","steps":["trace[2107313801] 'agreement among raft nodes before linearized reading' (duration: 1.632661034s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:22.556133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:20.923294Z","time spent":"1.632828489s","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:07:22.556154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054871268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-06T05:07:22.556147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.052021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:22.556223Z","caller":"traceutil/trace.go:171","msg":"trace[1696982444] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1241; }","duration":"1.05496648s","start":"2026-05-06T05:07:21.501238Z","end":"2026-05-06T05:07:22.556204Z","steps":["trace[1696982444] 'agreement among raft nodes before linearized reading' (duration: 1.054755555s)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:22.556263Z","caller":"traceutil/trace.go:171","msg":"trace[579969442] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1241; }","duration":"890.179864ms","start":"2026-05-06T05:07:21.666067Z","end":"2026-05-06T05:07:22.556247Z","steps":["trace[579969442] 'agreement among raft nodes before linearized reading' (duration: 890.01492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:22.556266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:21.501224Z","time spent":"1.055031311s","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-05-06T05:07:22.556246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.599217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:22.556369Z","caller":"traceutil/trace.go:171","msg":"trace[1128865275] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1241; }","duration":"800.795521ms","start":"2026-05-06T05:07:21.755553Z","end":"2026-05-06T05:07:22.556348Z","steps":["trace[1128865275] 'agreement among raft nodes before linearized reading' (duration: 800.568596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:22.556406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053894454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ace19eea56f55f\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-05-06T05:07:22.55643Z","caller":"traceutil/trace.go:171","msg":"trace[1140408378] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18ace19eea56f55f; range_end:; response_count:1; response_revision:1241; }","duration":"1.053925346s","start":"2026-05-06T05:07:21.502495Z","end":"2026-05-06T05:07:22.556421Z","steps":["trace[1140408378] 'agreement among raft nodes before linearized reading' (duration: 1.053826254s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:22.556455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:21.50249Z","time spent":"1.053961787s","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ace19eea56f55f\" "} {"level":"warn","ts":"2026-05-06T05:07:22.5563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:21.665999Z","time spent":"890.292377ms","remote":"127.0.0.1:60670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:07:22.556425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:21.75553Z","time spent":"800.882484ms","remote":"127.0.0.1:50046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:07:23.02676Z","caller":"traceutil/trace.go:171","msg":"trace[1751392850] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1295; }","duration":"460.825325ms","start":"2026-05-06T05:07:22.565907Z","end":"2026-05-06T05:07:23.026732Z","steps":["trace[1751392850] 'read index received' (duration: 460.59626ms)","trace[1751392850] 'applied index is now lower than readState.Index' (duration: 227.955µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:23.026998Z","caller":"traceutil/trace.go:171","msg":"trace[946521735] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"467.619895ms","start":"2026-05-06T05:07:22.559356Z","end":"2026-05-06T05:07:23.026976Z","steps":["trace[946521735] 'process raft request' (duration: 467.297317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:23.027118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.199274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:23.027179Z","caller":"traceutil/trace.go:171","msg":"trace[340139260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"461.296916ms","start":"2026-05-06T05:07:22.565865Z","end":"2026-05-06T05:07:23.027162Z","steps":["trace[340139260] 'agreement among raft nodes before linearized reading' (duration: 461.200794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:23.027249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:22.565851Z","time spent":"461.386138ms","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T05:07:23.027031Z","caller":"traceutil/trace.go:171","msg":"trace[1418147546] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"467.662616ms","start":"2026-05-06T05:07:22.559346Z","end":"2026-05-06T05:07:23.027009Z","steps":["trace[1418147546] 'process raft request' (duration: 467.103493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:23.027448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:22.559339Z","time spent":"467.809999ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:23.027424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:22.559324Z","time spent":"468.048805ms","remote":"127.0.0.1:60436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:07:23.782321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.899797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:23.782427Z","caller":"traceutil/trace.go:171","msg":"trace[228205953] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"755.477585ms","start":"2026-05-06T05:07:23.026933Z","end":"2026-05-06T05:07:23.782411Z","steps":["trace[228205953] 'read index received' (duration: 175.242931ms)","trace[228205953] 'applied index is now lower than readState.Index' (duration: 580.233634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:23.782582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.452854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T05:07:23.782617Z","caller":"traceutil/trace.go:171","msg":"trace[80035424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1244; }","duration":"863.526946ms","start":"2026-05-06T05:07:22.919079Z","end":"2026-05-06T05:07:23.782606Z","steps":["trace[80035424] 'agreement among raft nodes before linearized reading' (duration: 863.374622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:23.78265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:22.919061Z","time spent":"863.581448ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T05:07:23.78269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.844706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T05:07:23.782753Z","caller":"traceutil/trace.go:171","msg":"trace[1939153468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1244; }","duration":"861.930349ms","start":"2026-05-06T05:07:22.920807Z","end":"2026-05-06T05:07:23.782737Z","steps":["trace[1939153468] 'agreement among raft nodes before linearized reading' (duration: 861.777635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:23.7828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:22.920797Z","time spent":"861.99382ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T05:07:23.782846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.642706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-06T05:07:23.782922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.106796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T05:07:23.782946Z","caller":"traceutil/trace.go:171","msg":"trace[618395842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1244; }","duration":"862.799169ms","start":"2026-05-06T05:07:22.920123Z","end":"2026-05-06T05:07:23.782922Z","steps":["trace[618395842] 'agreement among raft nodes before linearized reading' (duration: 862.495052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:23.78302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:22.920103Z","time spent":"862.896221ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T05:07:23.783039Z","caller":"traceutil/trace.go:171","msg":"trace[1640869608] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"862.119373ms","start":"2026-05-06T05:07:22.920905Z","end":"2026-05-06T05:07:23.783025Z","steps":["trace[1640869608] 'process raft request' (duration: 281.417058ms)","trace[1640869608] 'compare' (duration: 579.6482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:23.783106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:22.920888Z","time spent":"862.179615ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:07:23.782961Z","caller":"traceutil/trace.go:171","msg":"trace[957272206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1244; }","duration":"863.181168ms","start":"2026-05-06T05:07:22.919769Z","end":"2026-05-06T05:07:23.78295Z","steps":["trace[957272206] 'agreement among raft nodes before linearized reading' (duration: 863.070785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:23.783301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:22.919748Z","time spent":"863.482336ms","remote":"127.0.0.1:60638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T05:07:24.309725Z","caller":"traceutil/trace.go:171","msg":"trace[916654438] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"179.264955ms","start":"2026-05-06T05:07:24.130443Z","end":"2026-05-06T05:07:24.309708Z","steps":["trace[916654438] 'process raft request' (duration: 141.483117ms)","trace[916654438] 'compare' (duration: 37.266846ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:24.663424Z","caller":"traceutil/trace.go:171","msg":"trace[943753303] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"104.832585ms","start":"2026-05-06T05:07:24.558561Z","end":"2026-05-06T05:07:24.663394Z","steps":["trace[943753303] 'read index received' (duration: 104.566449ms)","trace[943753303] 'applied index is now lower than readState.Index' (duration: 264.736µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:24.663528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.946947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:07:24.663584Z","caller":"traceutil/trace.go:171","msg":"trace[1093591451] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1252; }","duration":"105.0634ms","start":"2026-05-06T05:07:24.558509Z","end":"2026-05-06T05:07:24.663572Z","steps":["trace[1093591451] 'agreement among raft nodes before linearized reading' (duration: 104.921227ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:24.663777Z","caller":"traceutil/trace.go:171","msg":"trace[700713773] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"149.71788ms","start":"2026-05-06T05:07:24.51369Z","end":"2026-05-06T05:07:24.663408Z","steps":["trace[700713773] 'process raft request' (duration: 149.492335ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:27.648101Z","caller":"traceutil/trace.go:171","msg":"trace[43861801] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"101.729212ms","start":"2026-05-06T05:07:27.546345Z","end":"2026-05-06T05:07:27.648074Z","steps":["trace[43861801] 'process raft request' (duration: 101.472966ms)"],"step_count":1}