{"level":"warn","ts":"2026-05-19T20:01:29.750376Z","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-19T20:01:29.750496Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-19T20:01:29.750599Z","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-19T20:01:29.750622Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-19T20:01:29.750669Z","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-19T20:01:29.75156Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-05-19T20:01:29.751876Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-19T20:01:29.756977Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.618184ms"} {"level":"info","ts":"2026-05-19T20:01:29.76407Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-05-19T20:01:29.764186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T20:01:29.764213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-05-19T20:01:29.764235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T20:01:29.764251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-05-19T20:01:29.764306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-05-19T20:01:29.772763Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T20:01:29.774029Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T20:01:29.775164Z","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-19T20:01:29.777446Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T20:01:29.778096Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T20:01:29.778232Z","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-19T20:01:29.778269Z","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-19T20:01:29.778278Z","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-19T20:01:29.778533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-05-19T20:01:29.778598Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-19T20:01:29.789812Z","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-19T20:01:29.790001Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-19T20:01:29.790029Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-19T20:01:29.790177Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T20:01:29.790222Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T20:01:30.36468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T20:01:30.364735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T20:01:30.364773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-05-19T20:01:30.364793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-05-19T20:01:30.36481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-19T20:01:30.364838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-05-19T20:01:30.364874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-19T20:01:30.366196Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T20:01:30.366405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T20:01:30.366383Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T20:01:30.366846Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T20:01:30.368953Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T20:01:30.369003Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T20:01:30.369292Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T20:01:30.369451Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T20:01:30.369487Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T20:01:30.370256Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T20:01:30.370727Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-05-19T20:01:32.402995Z","caller":"traceutil/trace.go:171","msg":"trace[1842568738] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"459.117259ms","start":"2026-05-19T20:01:31.943859Z","end":"2026-05-19T20:01:32.402976Z","steps":["trace[1842568738] 'read index received' (duration: 459.015506ms)","trace[1842568738] 'applied index is now lower than readState.Index' (duration: 100.883µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:01:32.403022Z","caller":"traceutil/trace.go:171","msg":"trace[60849396] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"459.860689ms","start":"2026-05-19T20:01:31.943133Z","end":"2026-05-19T20:01:32.402994Z","steps":["trace[60849396] 'process raft request' (duration: 459.675965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.403163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.280814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T20:01:32.403292Z","caller":"traceutil/trace.go:171","msg":"trace[105311996] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"459.444239ms","start":"2026-05-19T20:01:31.943839Z","end":"2026-05-19T20:01:32.403283Z","steps":["trace[105311996] 'agreement among raft nodes before linearized reading' (duration: 459.233442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.403321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:31.943831Z","time spent":"459.482699ms","remote":"127.0.0.1:40658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":27,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-19T20:01:32.403792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:31.943066Z","time spent":"460.142147ms","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T20:01:32.903986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567156047389825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T20:01:32.982919Z","caller":"traceutil/trace.go:171","msg":"trace[1036977967] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"959.506382ms","start":"2026-05-19T20:01:32.023363Z","end":"2026-05-19T20:01:32.98287Z","steps":["trace[1036977967] 'process raft request' (duration: 919.558594ms)","trace[1036977967] 'compare' (duration: 39.66105ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:01:32.983116Z","caller":"traceutil/trace.go:171","msg":"trace[19701593] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"959.288566ms","start":"2026-05-19T20:01:32.023782Z","end":"2026-05-19T20:01:32.983071Z","steps":["trace[19701593] 'process raft request' (duration: 959.109932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.983146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.023348Z","time spent":"959.685437ms","remote":"127.0.0.1:41008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T20:01:32.983164Z","caller":"traceutil/trace.go:171","msg":"trace[853032961] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"959.658557ms","start":"2026-05-19T20:01:32.023463Z","end":"2026-05-19T20:01:32.983122Z","steps":["trace[853032961] 'process raft request' (duration: 959.331627ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:01:32.983211Z","caller":"traceutil/trace.go:171","msg":"trace[1974093769] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"959.556814ms","start":"2026-05-19T20:01:32.023587Z","end":"2026-05-19T20:01:32.983143Z","steps":["trace[1974093769] 'process raft request' (duration: 959.251436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.983315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.023764Z","time spent":"959.44087ms","remote":"127.0.0.1:41008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T20:01:32.983323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.023456Z","time spent":"959.79049ms","remote":"127.0.0.1:41008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T20:01:32.983344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.02356Z","time spent":"959.726608ms","remote":"127.0.0.1:41008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T20:01:32.983321Z","caller":"traceutil/trace.go:171","msg":"trace[809006847] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"941.544272ms","start":"2026-05-19T20:01:32.041719Z","end":"2026-05-19T20:01:32.983264Z","steps":["trace[809006847] 'process raft request' (duration: 941.344277ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:01:32.983148Z","caller":"traceutil/trace.go:171","msg":"trace[1009510913] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"580.075345ms","start":"2026-05-19T20:01:32.40305Z","end":"2026-05-19T20:01:32.983125Z","steps":["trace[1009510913] 'read index received' (duration: 539.847038ms)","trace[1009510913] 'applied index is now lower than readState.Index' (duration: 40.226847ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:01:32.9835Z","caller":"traceutil/trace.go:171","msg":"trace[1652341811] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"955.245838ms","start":"2026-05-19T20:01:32.028242Z","end":"2026-05-19T20:01:32.983488Z","steps":["trace[1652341811] 'process raft request' (duration: 954.714564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.983497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.19318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T20:01:32.983548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.041697Z","time spent":"941.761857ms","remote":"127.0.0.1:40658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-19T20:01:32.983567Z","caller":"traceutil/trace.go:171","msg":"trace[1207253367] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"960.313734ms","start":"2026-05-19T20:01:32.023233Z","end":"2026-05-19T20:01:32.983546Z","steps":["trace[1207253367] 'agreement among raft nodes before linearized reading' (duration: 960.038906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.983565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.028227Z","time spent":"955.30067ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T20:01:32.983627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.023149Z","time spent":"960.467088ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-19T20:01:32.9835Z","caller":"traceutil/trace.go:171","msg":"trace[954154384] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"952.34728ms","start":"2026-05-19T20:01:32.031145Z","end":"2026-05-19T20:01:32.983492Z","steps":["trace[954154384] 'process raft request' (duration: 951.871388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.983733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.031122Z","time spent":"952.576567ms","remote":"127.0.0.1:40726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T20:01:32.984896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.043612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T20:01:32.985263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041141085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T20:01:32.98533Z","caller":"traceutil/trace.go:171","msg":"trace[1672630213] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"1.041241508s","start":"2026-05-19T20:01:31.944065Z","end":"2026-05-19T20:01:32.985307Z","steps":["trace[1672630213] 'agreement among raft nodes before linearized reading' (duration: 1.039124131s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.985366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:31.944048Z","time spent":"1.04131137s","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-19T20:01:32.985264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.245087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T20:01:32.985301Z","caller":"traceutil/trace.go:171","msg":"trace[883698902] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"989.154315ms","start":"2026-05-19T20:01:31.995805Z","end":"2026-05-19T20:01:32.984959Z","steps":["trace[883698902] 'agreement among raft nodes before linearized reading' (duration: 987.962743ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:01:32.985712Z","caller":"traceutil/trace.go:171","msg":"trace[196772327] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"992.575956ms","start":"2026-05-19T20:01:31.992956Z","end":"2026-05-19T20:01:32.985532Z","steps":["trace[196772327] 'agreement among raft nodes before linearized reading' (duration: 992.213926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.985796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:31.992934Z","time spent":"992.846264ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-19T20:01:32.98602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:31.995797Z","time spent":"989.914615ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-19T20:01:32.986336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.561818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T20:01:32.986372Z","caller":"traceutil/trace.go:171","msg":"trace[2098004551] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"568.624699ms","start":"2026-05-19T20:01:32.417737Z","end":"2026-05-19T20:01:32.986362Z","steps":["trace[2098004551] 'agreement among raft nodes before linearized reading' (duration: 568.469004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.9864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.417722Z","time spent":"568.67244ms","remote":"127.0.0.1:40852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-19T20:01:32.986713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.32648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T20:01:32.98686Z","caller":"traceutil/trace.go:171","msg":"trace[725589526] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"906.494394ms","start":"2026-05-19T20:01:32.080334Z","end":"2026-05-19T20:01:32.986828Z","steps":["trace[725589526] 'agreement among raft nodes before linearized reading' (duration: 906.212747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.98701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.080313Z","time spent":"906.653639ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-19T20:01:32.987049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.118476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-19T20:01:32.987148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.491893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T20:01:32.987192Z","caller":"traceutil/trace.go:171","msg":"trace[1102031743] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"894.557845ms","start":"2026-05-19T20:01:32.092609Z","end":"2026-05-19T20:01:32.987167Z","steps":["trace[1102031743] 'agreement among raft nodes before linearized reading' (duration: 894.503144ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:01:32.98719Z","caller":"traceutil/trace.go:171","msg":"trace[506652923] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:0; response_revision:10; }","duration":"967.26638ms","start":"2026-05-19T20:01:32.019877Z","end":"2026-05-19T20:01:32.987143Z","steps":["trace[506652923] 'agreement among raft nodes before linearized reading' (duration: 967.064154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.987225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.092583Z","time spent":"894.636607ms","remote":"127.0.0.1:40582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-19T20:01:32.987265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.019869Z","time spent":"967.383253ms","remote":"127.0.0.1:40516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"info","ts":"2026-05-19T20:01:32.987323Z","caller":"traceutil/trace.go:171","msg":"trace[63498225] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"963.447178ms","start":"2026-05-19T20:01:32.023865Z","end":"2026-05-19T20:01:32.987312Z","steps":["trace[63498225] 'process raft request' (duration: 959.057371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.987387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.023853Z","time spent":"963.489258ms","remote":"127.0.0.1:41008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T20:01:32.98763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.941133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-19T20:01:32.987818Z","caller":"traceutil/trace.go:171","msg":"trace[2041897055] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"963.14844ms","start":"2026-05-19T20:01:32.024639Z","end":"2026-05-19T20:01:32.987787Z","steps":["trace[2041897055] 'agreement among raft nodes before linearized reading' (duration: 962.869482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:01:32.987933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:01:32.02462Z","time spent":"963.296714ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-19T20:02:02.668104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.843584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:02:02.668213Z","caller":"traceutil/trace.go:171","msg":"trace[1553051169] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:512; }","duration":"924.998718ms","start":"2026-05-19T20:02:01.743189Z","end":"2026-05-19T20:02:02.668187Z","steps":["trace[1553051169] 'range keys from in-memory index tree' (duration: 924.767152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:02.668224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.04261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:02:02.668292Z","caller":"traceutil/trace.go:171","msg":"trace[2794042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"593.143524ms","start":"2026-05-19T20:02:02.075128Z","end":"2026-05-19T20:02:02.668271Z","steps":["trace[2794042] 'range keys from in-memory index tree' (duration: 592.979389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:02.668279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:01.743169Z","time spent":"925.094722ms","remote":"127.0.0.1:40572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-19T20:02:02.668339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:02.07511Z","time spent":"593.216355ms","remote":"127.0.0.1:40496","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-19T20:02:02.668506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.516966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-19T20:02:02.6682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.506191ms","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-19T20:02:02.66859Z","caller":"traceutil/trace.go:171","msg":"trace[1535599658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"924.625538ms","start":"2026-05-19T20:02:01.743935Z","end":"2026-05-19T20:02:02.668561Z","steps":["trace[1535599658] 'range keys from in-memory index tree' (duration: 924.426504ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:02.668636Z","caller":"traceutil/trace.go:171","msg":"trace[977312091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"243.990904ms","start":"2026-05-19T20:02:02.42462Z","end":"2026-05-19T20:02:02.668611Z","steps":["trace[977312091] 'range keys from in-memory index tree' (duration: 243.235054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:02.66865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:01.743923Z","time spent":"924.713101ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T20:02:06.51511Z","caller":"traceutil/trace.go:171","msg":"trace[809495709] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"170.791608ms","start":"2026-05-19T20:02:06.344287Z","end":"2026-05-19T20:02:06.515078Z","steps":["trace[809495709] 'process raft request' (duration: 170.017468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:07.161761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.242559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-19T20:02:07.161862Z","caller":"traceutil/trace.go:171","msg":"trace[105453300] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"462.0738ms","start":"2026-05-19T20:02:06.699772Z","end":"2026-05-19T20:02:07.161845Z","steps":["trace[105453300] 'read index received' (duration: 54.942µs)","trace[105453300] 'applied index is now lower than readState.Index' (duration: 462.017748ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:02:07.16193Z","caller":"traceutil/trace.go:171","msg":"trace[1805971145] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"582.49874ms","start":"2026-05-19T20:02:06.579407Z","end":"2026-05-19T20:02:07.161906Z","steps":["trace[1805971145] 'process raft request' (duration: 111.915544ms)","trace[1805971145] 'compare' (duration: 470.110503ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:02:07.162033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:06.579386Z","time spent":"582.598013ms","remote":"127.0.0.1:40680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T20:02:07.162062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.283765ms","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-19T20:02:07.162094Z","caller":"traceutil/trace.go:171","msg":"trace[509095396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"462.354997ms","start":"2026-05-19T20:02:06.69973Z","end":"2026-05-19T20:02:07.162085Z","steps":["trace[509095396] 'agreement among raft nodes before linearized reading' (duration: 462.199493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:07.162105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.908518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-19T20:02:07.162135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:06.69971Z","time spent":"462.416868ms","remote":"127.0.0.1:40732","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-19T20:02:07.162171Z","caller":"traceutil/trace.go:171","msg":"trace[1792226905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"460.998452ms","start":"2026-05-19T20:02:06.701154Z","end":"2026-05-19T20:02:07.162153Z","steps":["trace[1792226905] 'agreement among raft nodes before linearized reading' (duration: 460.793586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:07.162218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:06.701143Z","time spent":"461.067043ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T20:02:07.162679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.012364ms","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-19T20:02:07.162733Z","caller":"traceutil/trace.go:171","msg":"trace[1465897349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"457.148498ms","start":"2026-05-19T20:02:06.705571Z","end":"2026-05-19T20:02:07.16272Z","steps":["trace[1465897349] 'agreement among raft nodes before linearized reading' (duration: 456.401478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:07.162765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:06.705542Z","time spent":"457.21557ms","remote":"127.0.0.1:40732","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-19T20:02:09.969398Z","caller":"traceutil/trace.go:171","msg":"trace[1060225890] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"361.959871ms","start":"2026-05-19T20:02:09.607388Z","end":"2026-05-19T20:02:09.969348Z","steps":["trace[1060225890] 'process raft request' (duration: 361.703835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:09.969607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:09.607364Z","time spent":"362.138386ms","remote":"127.0.0.1:40662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T20:02:10.010125Z","caller":"traceutil/trace.go:171","msg":"trace[1344532445] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"253.239883ms","start":"2026-05-19T20:02:09.756853Z","end":"2026-05-19T20:02:10.010093Z","steps":["trace[1344532445] 'read index received' (duration: 212.487953ms)","trace[1344532445] 'applied index is now lower than readState.Index' (duration: 40.75094ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:02:10.010178Z","caller":"traceutil/trace.go:171","msg":"trace[706593377] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"368.753693ms","start":"2026-05-19T20:02:09.641393Z","end":"2026-05-19T20:02:10.010147Z","steps":["trace[706593377] 'process raft request' (duration: 368.014183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:10.010303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.423398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:02:10.010368Z","caller":"traceutil/trace.go:171","msg":"trace[169676466] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:624; }","duration":"253.529282ms","start":"2026-05-19T20:02:09.756823Z","end":"2026-05-19T20:02:10.010352Z","steps":["trace[169676466] 'agreement among raft nodes before linearized reading' (duration: 253.399568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:10.010467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:09.641364Z","time spent":"368.903197ms","remote":"127.0.0.1:40572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T20:02:10.010325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.290355ms","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-19T20:02:10.010551Z","caller":"traceutil/trace.go:171","msg":"trace[1705944856] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:624; }","duration":"253.587482ms","start":"2026-05-19T20:02:09.756953Z","end":"2026-05-19T20:02:10.01054Z","steps":["trace[1705944856] 'agreement among raft nodes before linearized reading' (duration: 253.300425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:10.011051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.073406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T20:02:10.011098Z","caller":"traceutil/trace.go:171","msg":"trace[1782308540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"162.158788ms","start":"2026-05-19T20:02:09.848929Z","end":"2026-05-19T20:02:10.011087Z","steps":["trace[1782308540] 'agreement among raft nodes before linearized reading' (duration: 162.075156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:11.276202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.291241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T20:02:11.27644Z","caller":"traceutil/trace.go:171","msg":"trace[472596225] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.204330793s","start":"2026-05-19T20:02:10.072079Z","end":"2026-05-19T20:02:11.27641Z","steps":["trace[472596225] 'process raft request' (duration: 1.204256201s)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:11.276451Z","caller":"traceutil/trace.go:171","msg":"trace[1708923311] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.259396616s","start":"2026-05-19T20:02:10.017026Z","end":"2026-05-19T20:02:11.276423Z","steps":["trace[1708923311] 'process raft request' (duration: 438.787077ms)","trace[1708923311] 'compare' (duration: 820.121286ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:02:11.276541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:10.072042Z","time spent":"1.204448056s","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-19T20:02:11.276571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:10.017011Z","time spent":"1.259496809s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T20:02:11.276574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.915214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:02:11.276632Z","caller":"traceutil/trace.go:171","msg":"trace[2122661268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"330.010948ms","start":"2026-05-19T20:02:10.94661Z","end":"2026-05-19T20:02:11.276621Z","steps":["trace[2122661268] 'agreement among raft nodes before linearized reading' (duration: 329.909824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:11.276666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:10.946589Z","time spent":"330.070099ms","remote":"127.0.0.1:40496","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-19T20:02:11.276457Z","caller":"traceutil/trace.go:171","msg":"trace[208642397] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"329.72443ms","start":"2026-05-19T20:02:10.946663Z","end":"2026-05-19T20:02:11.276387Z","steps":["trace[208642397] 'read index received' (duration: 70.674671ms)","trace[208642397] 'applied index is now lower than readState.Index' (duration: 259.048919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:02:11.277176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.186757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:02:11.277754Z","caller":"traceutil/trace.go:171","msg":"trace[207359058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"205.255009ms","start":"2026-05-19T20:02:11.071943Z","end":"2026-05-19T20:02:11.277198Z","steps":["trace[207359058] 'agreement among raft nodes before linearized reading' (duration: 205.206728ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:12.904668Z","caller":"traceutil/trace.go:171","msg":"trace[1310150189] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"177.297772ms","start":"2026-05-19T20:02:12.727328Z","end":"2026-05-19T20:02:12.904625Z","steps":["trace[1310150189] 'process raft request' (duration: 176.963872ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:12.912163Z","caller":"traceutil/trace.go:171","msg":"trace[1406981765] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"184.772572ms","start":"2026-05-19T20:02:12.727373Z","end":"2026-05-19T20:02:12.912146Z","steps":["trace[1406981765] 'process raft request' (duration: 184.454143ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:12.91255Z","caller":"traceutil/trace.go:171","msg":"trace[1213771431] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"185.025188ms","start":"2026-05-19T20:02:12.727512Z","end":"2026-05-19T20:02:12.912537Z","steps":["trace[1213771431] 'process raft request' (duration: 184.464813ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:15.959356Z","caller":"traceutil/trace.go:171","msg":"trace[494669306] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"163.981976ms","start":"2026-05-19T20:02:15.795355Z","end":"2026-05-19T20:02:15.959337Z","steps":["trace[494669306] 'process raft request' (duration: 163.637817ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:22.978896Z","caller":"traceutil/trace.go:171","msg":"trace[1246209530] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:790; }","duration":"214.861378ms","start":"2026-05-19T20:02:22.764008Z","end":"2026-05-19T20:02:22.97887Z","steps":["trace[1246209530] 'read index received' (duration: 16.703917ms)","trace[1246209530] 'applied index is now lower than readState.Index' (duration: 198.15625ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:02:22.979112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.081683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T20:02:22.979156Z","caller":"traceutil/trace.go:171","msg":"trace[1318163149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"215.164045ms","start":"2026-05-19T20:02:22.76398Z","end":"2026-05-19T20:02:22.979144Z","steps":["trace[1318163149] 'agreement among raft nodes before linearized reading' (duration: 215.006881ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:22.979197Z","caller":"traceutil/trace.go:171","msg":"trace[1715297472] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"289.455392ms","start":"2026-05-19T20:02:22.689716Z","end":"2026-05-19T20:02:22.979172Z","steps":["trace[1715297472] 'process raft request' (duration: 278.601732ms)","trace[1715297472] 'compare' (duration: 10.181882ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:02:22.979307Z","caller":"traceutil/trace.go:171","msg":"trace[1919926411] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"285.099935ms","start":"2026-05-19T20:02:22.694195Z","end":"2026-05-19T20:02:22.979295Z","steps":["trace[1919926411] 'process raft request' (duration: 284.589382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:23.617637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.407623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T20:02:23.617746Z","caller":"traceutil/trace.go:171","msg":"trace[1132613135] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"241.127879ms","start":"2026-05-19T20:02:23.3766Z","end":"2026-05-19T20:02:23.617728Z","steps":["trace[1132613135] 'process raft request' (duration: 84.556842ms)","trace[1132613135] 'compare' (duration: 156.28322ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:02:23.61917Z","caller":"traceutil/trace.go:171","msg":"trace[289488715] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"240.02615ms","start":"2026-05-19T20:02:23.379125Z","end":"2026-05-19T20:02:23.619152Z","steps":["trace[289488715] 'process raft request' (duration: 239.971359ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:23.619297Z","caller":"traceutil/trace.go:171","msg":"trace[125188866] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"240.243366ms","start":"2026-05-19T20:02:23.379019Z","end":"2026-05-19T20:02:23.619263Z","steps":["trace[125188866] 'process raft request' (duration: 239.990659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:26.813005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.469905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:02:26.813192Z","caller":"traceutil/trace.go:171","msg":"trace[702091232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"740.670791ms","start":"2026-05-19T20:02:26.0725Z","end":"2026-05-19T20:02:26.813171Z","steps":["trace[702091232] 'range keys from in-memory index tree' (duration: 740.405184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:26.813265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:02:26.072447Z","time spent":"740.806485ms","remote":"127.0.0.1:40496","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-19T20:02:26.813433Z","caller":"traceutil/trace.go:171","msg":"trace[1377421456] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"135.600217ms","start":"2026-05-19T20:02:26.677801Z","end":"2026-05-19T20:02:26.813402Z","steps":["trace[1377421456] 'process raft request' (duration: 133.845159ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:26.815876Z","caller":"traceutil/trace.go:171","msg":"trace[1930650791] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"115.718105ms","start":"2026-05-19T20:02:26.700122Z","end":"2026-05-19T20:02:26.81584Z","steps":["trace[1930650791] 'process raft request' (duration: 115.350795ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:55.989384Z","caller":"traceutil/trace.go:171","msg":"trace[1885480687] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"156.28344ms","start":"2026-05-19T20:02:55.833078Z","end":"2026-05-19T20:02:55.989361Z","steps":["trace[1885480687] 'read index received' (duration: 156.108186ms)","trace[1885480687] 'applied index is now lower than readState.Index' (duration: 174.004µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:02:55.989504Z","caller":"traceutil/trace.go:171","msg":"trace[665741138] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"202.887456ms","start":"2026-05-19T20:02:55.786578Z","end":"2026-05-19T20:02:55.989466Z","steps":["trace[665741138] 'process raft request' (duration: 202.622559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:55.98954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.423204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:02:55.989595Z","caller":"traceutil/trace.go:171","msg":"trace[903637214] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:987; }","duration":"156.509087ms","start":"2026-05-19T20:02:55.833072Z","end":"2026-05-19T20:02:55.989581Z","steps":["trace[903637214] 'agreement among raft nodes before linearized reading' (duration: 156.383263ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:02:55.995674Z","caller":"traceutil/trace.go:171","msg":"trace[2058440041] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"160.758429ms","start":"2026-05-19T20:02:55.834879Z","end":"2026-05-19T20:02:55.995637Z","steps":["trace[2058440041] 'process raft request' (duration: 160.638426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:55.995745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.95385ms","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-19T20:02:55.995801Z","caller":"traceutil/trace.go:171","msg":"trace[1112724672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:988; }","duration":"150.038323ms","start":"2026-05-19T20:02:55.845751Z","end":"2026-05-19T20:02:55.995789Z","steps":["trace[1112724672] 'agreement among raft nodes before linearized reading' (duration: 149.854888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:02:55.995957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.704967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T20:02:55.995994Z","caller":"traceutil/trace.go:171","msg":"trace[1056393600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:988; }","duration":"145.754239ms","start":"2026-05-19T20:02:55.850231Z","end":"2026-05-19T20:02:55.995985Z","steps":["trace[1056393600] 'agreement among raft nodes before linearized reading' (duration: 145.657566ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:00.172536Z","caller":"traceutil/trace.go:171","msg":"trace[658695833] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"143.007075ms","start":"2026-05-19T20:03:00.029487Z","end":"2026-05-19T20:03:00.172494Z","steps":["trace[658695833] 'process raft request' (duration: 106.89433ms)","trace[658695833] 'compare' (duration: 35.90297ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:03:00.177351Z","caller":"traceutil/trace.go:171","msg":"trace[1060977631] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"146.123228ms","start":"2026-05-19T20:03:00.031211Z","end":"2026-05-19T20:03:00.177334Z","steps":["trace[1060977631] 'process raft request' (duration: 146.075926ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:00.177694Z","caller":"traceutil/trace.go:171","msg":"trace[772245752] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"146.95569ms","start":"2026-05-19T20:03:00.030727Z","end":"2026-05-19T20:03:00.177683Z","steps":["trace[772245752] 'process raft request' (duration: 146.457757ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:01.470607Z","caller":"traceutil/trace.go:171","msg":"trace[428868636] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"238.656693ms","start":"2026-05-19T20:03:01.231912Z","end":"2026-05-19T20:03:01.470569Z","steps":["trace[428868636] 'process raft request' (duration: 238.503399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:01.907689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.466384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T20:03:01.907767Z","caller":"traceutil/trace.go:171","msg":"trace[241174898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"267.627408ms","start":"2026-05-19T20:03:01.640122Z","end":"2026-05-19T20:03:01.907749Z","steps":["trace[241174898] 'range keys from in-memory index tree' (duration: 267.258279ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:02.164478Z","caller":"traceutil/trace.go:171","msg":"trace[1774369136] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"232.16796ms","start":"2026-05-19T20:03:01.932266Z","end":"2026-05-19T20:03:02.164434Z","steps":["trace[1774369136] 'process raft request' (duration: 232.005205ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:02.178567Z","caller":"traceutil/trace.go:171","msg":"trace[1058163327] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1046; }","duration":"150.185927ms","start":"2026-05-19T20:03:02.028358Z","end":"2026-05-19T20:03:02.178544Z","steps":["trace[1058163327] 'read index received' (duration: 135.882254ms)","trace[1058163327] 'applied index is now lower than readState.Index' (duration: 14.302763ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:03:02.178673Z","caller":"traceutil/trace.go:171","msg":"trace[270902097] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"164.222963ms","start":"2026-05-19T20:03:02.01444Z","end":"2026-05-19T20:03:02.178663Z","steps":["trace[270902097] 'process raft request' (duration: 164.009818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:02.178755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.332937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:03:02.178801Z","caller":"traceutil/trace.go:171","msg":"trace[1017418433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"102.441849ms","start":"2026-05-19T20:03:02.076346Z","end":"2026-05-19T20:03:02.178788Z","steps":["trace[1017418433] 'agreement among raft nodes before linearized reading' (duration: 102.368708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:02.179052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.708821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-19T20:03:02.179082Z","caller":"traceutil/trace.go:171","msg":"trace[1191342440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"150.738932ms","start":"2026-05-19T20:03:02.028334Z","end":"2026-05-19T20:03:02.179073Z","steps":["trace[1191342440] 'agreement among raft nodes before linearized reading' (duration: 150.629119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:02.507947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.96306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T20:03:02.508105Z","caller":"traceutil/trace.go:171","msg":"trace[783744616] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"322.437344ms","start":"2026-05-19T20:03:02.185654Z","end":"2026-05-19T20:03:02.508092Z","steps":["trace[783744616] 'process raft request' (duration: 322.394743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:02.508152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:03:02.18563Z","time spent":"322.499765ms","remote":"127.0.0.1:40732","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-19T20:03:02.508193Z","caller":"traceutil/trace.go:171","msg":"trace[521571376] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"324.593153ms","start":"2026-05-19T20:03:02.183582Z","end":"2026-05-19T20:03:02.508175Z","steps":["trace[521571376] 'read index received' (duration: 26.340715ms)","trace[521571376] 'applied index is now lower than readState.Index' (duration: 298.250657ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:03:02.508322Z","caller":"traceutil/trace.go:171","msg":"trace[771152251] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"325.293312ms","start":"2026-05-19T20:03:02.183002Z","end":"2026-05-19T20:03:02.508296Z","steps":["trace[771152251] 'process raft request' (duration: 26.908951ms)","trace[771152251] 'compare' (duration: 297.793325ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:03:02.508367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.775327ms","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-19T20:03:02.508401Z","caller":"traceutil/trace.go:171","msg":"trace[540770598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"324.832069ms","start":"2026-05-19T20:03:02.183558Z","end":"2026-05-19T20:03:02.50839Z","steps":["trace[540770598] 'agreement among raft nodes before linearized reading' (duration: 324.680065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:02.508433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:03:02.183548Z","time spent":"324.87775ms","remote":"127.0.0.1:40732","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-19T20:03:02.50847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:03:02.182978Z","time spent":"325.406755ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T20:03:04.171862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234436308s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T20:03:04.17196Z","caller":"traceutil/trace.go:171","msg":"trace[1323421880] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"1.395198628s","start":"2026-05-19T20:03:02.776745Z","end":"2026-05-19T20:03:04.171944Z","steps":["trace[1323421880] 'read index received' (duration: 160.612086ms)","trace[1323421880] 'applied index is now lower than readState.Index' (duration: 1.234585142s)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:03:04.172042Z","caller":"traceutil/trace.go:171","msg":"trace[1885161435] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.660072224s","start":"2026-05-19T20:03:02.511959Z","end":"2026-05-19T20:03:04.172031Z","steps":["trace[1885161435] 'process raft request' (duration: 425.386029ms)","trace[1885161435] 'compare' (duration: 1.233829932s)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:03:04.172093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:03:02.511938Z","time spent":"1.660127404s","remote":"127.0.0.1:40732","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-19T20:03:04.172305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081113159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:03:04.172406Z","caller":"traceutil/trace.go:171","msg":"trace[299388850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"1.081252632s","start":"2026-05-19T20:03:03.091129Z","end":"2026-05-19T20:03:04.172382Z","steps":["trace[299388850] 'agreement among raft nodes before linearized reading' (duration: 1.081100078s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:04.172468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:03:03.091114Z","time spent":"1.081340694s","remote":"127.0.0.1:40496","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-19T20:03:04.172793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.396039271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:03:04.172847Z","caller":"traceutil/trace.go:171","msg":"trace[2080026312] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1013; }","duration":"1.396118803s","start":"2026-05-19T20:03:02.776714Z","end":"2026-05-19T20:03:04.172833Z","steps":["trace[2080026312] 'agreement among raft nodes before linearized reading' (duration: 1.39601882s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:04.172889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:03:02.776699Z","time spent":"1.396178405s","remote":"127.0.0.1:40716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-05-19T20:03:04.173097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.423752ms","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-19T20:03:04.173145Z","caller":"traceutil/trace.go:171","msg":"trace[1913658478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"698.510894ms","start":"2026-05-19T20:03:03.474617Z","end":"2026-05-19T20:03:04.173128Z","steps":["trace[1913658478] 'agreement among raft nodes before linearized reading' (duration: 698.392211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:04.173186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:03:03.474577Z","time spent":"698.598047ms","remote":"127.0.0.1:40732","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-19T20:03:04.174196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.400065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T20:03:04.174259Z","caller":"traceutil/trace.go:171","msg":"trace[1048137856] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:1013; }","duration":"463.499028ms","start":"2026-05-19T20:03:03.710746Z","end":"2026-05-19T20:03:04.174245Z","steps":["trace[1048137856] 'agreement among raft nodes before linearized reading' (duration: 462.058429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:04.174311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:03:03.710726Z","time spent":"463.57417ms","remote":"127.0.0.1:40516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"info","ts":"2026-05-19T20:03:16.829319Z","caller":"traceutil/trace.go:171","msg":"trace[212352473] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"256.637745ms","start":"2026-05-19T20:03:16.57266Z","end":"2026-05-19T20:03:16.829298Z","steps":["trace[212352473] 'read index received' (duration: 256.486ms)","trace[212352473] 'applied index is now lower than readState.Index' (duration: 151.005µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:03:16.829491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.79518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T20:03:16.829458Z","caller":"traceutil/trace.go:171","msg":"trace[1023814990] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"258.979358ms","start":"2026-05-19T20:03:16.570435Z","end":"2026-05-19T20:03:16.829414Z","steps":["trace[1023814990] 'process raft request' (duration: 258.754641ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:16.829531Z","caller":"traceutil/trace.go:171","msg":"trace[1513152487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1058; }","duration":"256.886261ms","start":"2026-05-19T20:03:16.572633Z","end":"2026-05-19T20:03:16.829519Z","steps":["trace[1513152487] 'agreement among raft nodes before linearized reading' (duration: 256.743708ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:24.373908Z","caller":"traceutil/trace.go:171","msg":"trace[722106369] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"117.805431ms","start":"2026-05-19T20:03:24.256083Z","end":"2026-05-19T20:03:24.373889Z","steps":["trace[722106369] 'read index received' (duration: 117.604096ms)","trace[722106369] 'applied index is now lower than readState.Index' (duration: 200.425µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:03:24.373985Z","caller":"traceutil/trace.go:171","msg":"trace[1807933066] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"129.925044ms","start":"2026-05-19T20:03:24.244026Z","end":"2026-05-19T20:03:24.373952Z","steps":["trace[1807933066] 'process raft request' (duration: 129.748419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:24.374091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.472036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T20:03:24.374117Z","caller":"traceutil/trace.go:171","msg":"trace[2104425219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"113.526048ms","start":"2026-05-19T20:03:24.260583Z","end":"2026-05-19T20:03:24.374109Z","steps":["trace[2104425219] 'agreement among raft nodes before linearized reading' (duration: 113.401215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:24.374196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.099399ms","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-19T20:03:24.374231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.834381ms","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-19T20:03:24.374273Z","caller":"traceutil/trace.go:171","msg":"trace[304859582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"118.223343ms","start":"2026-05-19T20:03:24.256031Z","end":"2026-05-19T20:03:24.374254Z","steps":["trace[304859582] 'agreement among raft nodes before linearized reading' (duration: 117.997617ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:24.374295Z","caller":"traceutil/trace.go:171","msg":"trace[782659360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1080; }","duration":"111.973886ms","start":"2026-05-19T20:03:24.262304Z","end":"2026-05-19T20:03:24.374278Z","steps":["trace[782659360] 'agreement among raft nodes before linearized reading' (duration: 111.725089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:24.90092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.445386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T20:03:24.901142Z","caller":"traceutil/trace.go:171","msg":"trace[45762622] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"522.749641ms","start":"2026-05-19T20:03:24.378323Z","end":"2026-05-19T20:03:24.901073Z","steps":["trace[45762622] 'process raft request' (duration: 304.073149ms)","trace[45762622] 'compare' (duration: 218.116226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:03:24.901636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:03:24.378313Z","time spent":"522.913955ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T20:03:25.056466Z","caller":"traceutil/trace.go:171","msg":"trace[538274618] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"150.421541ms","start":"2026-05-19T20:03:24.906023Z","end":"2026-05-19T20:03:25.056445Z","steps":["trace[538274618] 'process raft request' (duration: 148.176922ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:25.058836Z","caller":"traceutil/trace.go:171","msg":"trace[1365452219] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"152.201389ms","start":"2026-05-19T20:03:24.906397Z","end":"2026-05-19T20:03:25.058598Z","steps":["trace[1365452219] 'process raft request' (duration: 152.068225ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:26.574997Z","caller":"traceutil/trace.go:171","msg":"trace[1018797159] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"111.040982ms","start":"2026-05-19T20:03:26.463933Z","end":"2026-05-19T20:03:26.574974Z","steps":["trace[1018797159] 'process raft request' (duration: 49.397138ms)","trace[1018797159] 'compare' (duration: 61.553142ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:03:27.089188Z","caller":"traceutil/trace.go:171","msg":"trace[1286480281] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"178.787688ms","start":"2026-05-19T20:03:26.910379Z","end":"2026-05-19T20:03:27.089166Z","steps":["trace[1286480281] 'process raft request' (duration: 178.49135ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:42.774592Z","caller":"traceutil/trace.go:171","msg":"trace[127311546] linearizableReadLoop","detail":"{readStateIndex:1238; appliedIndex:1237; }","duration":"212.153878ms","start":"2026-05-19T20:03:42.562422Z","end":"2026-05-19T20:03:42.774576Z","steps":["trace[127311546] 'read index received' (duration: 212.031965ms)","trace[127311546] 'applied index is now lower than readState.Index' (duration: 121.153µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:03:42.774804Z","caller":"traceutil/trace.go:171","msg":"trace[1010400539] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"263.806575ms","start":"2026-05-19T20:03:42.51099Z","end":"2026-05-19T20:03:42.774796Z","steps":["trace[1010400539] 'process raft request' (duration: 263.482816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:42.774954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.527528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T20:03:42.77497Z","caller":"traceutil/trace.go:171","msg":"trace[741873743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1190; }","duration":"212.57825ms","start":"2026-05-19T20:03:42.562386Z","end":"2026-05-19T20:03:42.774964Z","steps":["trace[741873743] 'agreement among raft nodes before linearized reading' (duration: 212.505678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:42.775063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.276404ms","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-19T20:03:42.775075Z","caller":"traceutil/trace.go:171","msg":"trace[519792642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1190; }","duration":"136.312015ms","start":"2026-05-19T20:03:42.638759Z","end":"2026-05-19T20:03:42.775071Z","steps":["trace[519792642] 'agreement among raft nodes before linearized reading' (duration: 136.275345ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:58.703856Z","caller":"traceutil/trace.go:171","msg":"trace[1973780639] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"238.49048ms","start":"2026-05-19T20:03:58.465341Z","end":"2026-05-19T20:03:58.703832Z","steps":["trace[1973780639] 'process raft request' (duration: 238.303545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:03:58.922842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.450983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:03:58.922933Z","caller":"traceutil/trace.go:171","msg":"trace[169904657] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1270; }","duration":"114.569086ms","start":"2026-05-19T20:03:58.808345Z","end":"2026-05-19T20:03:58.922914Z","steps":["trace[169904657] 'count revisions from in-memory index tree' (duration: 114.35767ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:03:58.97312Z","caller":"traceutil/trace.go:171","msg":"trace[129567667] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"120.424802ms","start":"2026-05-19T20:03:58.852671Z","end":"2026-05-19T20:03:58.973095Z","steps":["trace[129567667] 'process raft request' (duration: 120.291228ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:01.348825Z","caller":"traceutil/trace.go:171","msg":"trace[8626297] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"249.532974ms","start":"2026-05-19T20:04:01.099265Z","end":"2026-05-19T20:04:01.348798Z","steps":["trace[8626297] 'process raft request' (duration: 199.848829ms)","trace[8626297] 'compare' (duration: 49.533951ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:04:01.569315Z","caller":"traceutil/trace.go:171","msg":"trace[1175153644] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"216.662398ms","start":"2026-05-19T20:04:01.352636Z","end":"2026-05-19T20:04:01.569299Z","steps":["trace[1175153644] 'process raft request' (duration: 216.574236ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:01.58535Z","caller":"traceutil/trace.go:171","msg":"trace[1027726370] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"209.627641ms","start":"2026-05-19T20:04:01.375709Z","end":"2026-05-19T20:04:01.585337Z","steps":["trace[1027726370] 'read index received' (duration: 193.941373ms)","trace[1027726370] 'applied index is now lower than readState.Index' (duration: 15.685638ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:04:01.585383Z","caller":"traceutil/trace.go:171","msg":"trace[871306085] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"232.536471ms","start":"2026-05-19T20:04:01.352822Z","end":"2026-05-19T20:04:01.585359Z","steps":["trace[871306085] 'process raft request' (duration: 232.453999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:01.585471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.747304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T20:04:01.585499Z","caller":"traceutil/trace.go:171","msg":"trace[10378009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"209.845986ms","start":"2026-05-19T20:04:01.375646Z","end":"2026-05-19T20:04:01.585492Z","steps":["trace[10378009] 'agreement among raft nodes before linearized reading' (duration: 209.744633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:01.890748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.939645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:04:01.890813Z","caller":"traceutil/trace.go:171","msg":"trace[1058466506] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1284; }","duration":"214.040918ms","start":"2026-05-19T20:04:01.676755Z","end":"2026-05-19T20:04:01.890796Z","steps":["trace[1058466506] 'range keys from in-memory index tree' (duration: 213.879084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:01.891136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.849407ms","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-19T20:04:01.891166Z","caller":"traceutil/trace.go:171","msg":"trace[1230938382] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1284; }","duration":"185.914689ms","start":"2026-05-19T20:04:01.705243Z","end":"2026-05-19T20:04:01.891158Z","steps":["trace[1230938382] 'count revisions from in-memory index tree' (duration: 185.789086ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:05.528931Z","caller":"traceutil/trace.go:171","msg":"trace[1416508397] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"108.122613ms","start":"2026-05-19T20:04:05.420781Z","end":"2026-05-19T20:04:05.528904Z","steps":["trace[1416508397] 'process raft request' (duration: 107.99614ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:09.781651Z","caller":"traceutil/trace.go:171","msg":"trace[969662097] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"107.531547ms","start":"2026-05-19T20:04:09.674095Z","end":"2026-05-19T20:04:09.781627Z","steps":["trace[969662097] 'process raft request' (duration: 107.476716ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:09.781731Z","caller":"traceutil/trace.go:171","msg":"trace[446172336] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"108.162585ms","start":"2026-05-19T20:04:09.673516Z","end":"2026-05-19T20:04:09.781678Z","steps":["trace[446172336] 'process raft request' (duration: 71.23073ms)","trace[446172336] 'compare' (duration: 36.676308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:04:12.462156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.242128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T20:04:12.462244Z","caller":"traceutil/trace.go:171","msg":"trace[1451110859] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1409; }","duration":"613.10105ms","start":"2026-05-19T20:04:11.849131Z","end":"2026-05-19T20:04:12.462232Z","steps":["trace[1451110859] 'read index received' (duration: 105.74251ms)","trace[1451110859] 'applied index is now lower than readState.Index' (duration: 507.35777ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:04:12.462304Z","caller":"traceutil/trace.go:171","msg":"trace[661694189] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"673.191983ms","start":"2026-05-19T20:04:11.789105Z","end":"2026-05-19T20:04:12.462297Z","steps":["trace[661694189] 'process raft request' (duration: 165.74053ms)","trace[661694189] 'compare' (duration: 507.013052ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:04:12.462342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:11.789088Z","time spent":"673.233314ms","remote":"127.0.0.1:40732","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-19T20:04:12.462486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.360268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-19T20:04:12.462504Z","caller":"traceutil/trace.go:171","msg":"trace[326518129] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1356; }","duration":"613.407149ms","start":"2026-05-19T20:04:11.84909Z","end":"2026-05-19T20:04:12.462497Z","steps":["trace[326518129] 'agreement among raft nodes before linearized reading' (duration: 613.319787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:12.462523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:11.849076Z","time spent":"613.44266ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-19T20:04:12.462771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.519499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:04:12.462792Z","caller":"traceutil/trace.go:171","msg":"trace[66166403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"341.574179ms","start":"2026-05-19T20:04:12.121212Z","end":"2026-05-19T20:04:12.462786Z","steps":["trace[66166403] 'agreement among raft nodes before linearized reading' (duration: 341.537198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:12.46281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:12.121187Z","time spent":"341.619171ms","remote":"127.0.0.1:40496","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-19T20:04:12.462769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.110711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:04:12.462871Z","caller":"traceutil/trace.go:171","msg":"trace[1194565752] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1356; }","duration":"586.267465ms","start":"2026-05-19T20:04:11.876575Z","end":"2026-05-19T20:04:12.462843Z","steps":["trace[1194565752] 'agreement among raft nodes before linearized reading' (duration: 586.097461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:12.462959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:11.876556Z","time spent":"586.358258ms","remote":"127.0.0.1:50214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-19T20:04:12.463083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.024746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T20:04:12.463109Z","caller":"traceutil/trace.go:171","msg":"trace[378902295] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1356; }","duration":"497.084607ms","start":"2026-05-19T20:04:11.966015Z","end":"2026-05-19T20:04:12.4631Z","steps":["trace[378902295] 'agreement among raft nodes before linearized reading' (duration: 497.009045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:12.463129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:11.965995Z","time spent":"497.127948ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T20:04:13.238138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567156047392612,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T20:04:13.540022Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.036834372s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T20:04:13.568526Z","caller":"traceutil/trace.go:171","msg":"trace[804295499] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"1.100773408s","start":"2026-05-19T20:04:12.467732Z","end":"2026-05-19T20:04:13.568505Z","steps":["trace[804295499] 'process raft request' (duration: 1.072504253s)","trace[804295499] 'compare' (duration: 28.12269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:04:13.568639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:12.467721Z","time spent":"1.100857999s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T20:04:13.568736Z","caller":"traceutil/trace.go:171","msg":"trace[2036739568] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1411; }","duration":"830.93169ms","start":"2026-05-19T20:04:12.737754Z","end":"2026-05-19T20:04:13.568685Z","steps":["trace[2036739568] 'read index received' (duration: 802.496992ms)","trace[2036739568] 'applied index is now lower than readState.Index' (duration: 28.432938ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:04:13.568902Z","caller":"traceutil/trace.go:171","msg":"trace[860660367] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"493.86669ms","start":"2026-05-19T20:04:13.075011Z","end":"2026-05-19T20:04:13.568878Z","steps":["trace[860660367] 'process raft request' (duration: 493.4602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:13.568963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:13.074989Z","time spent":"493.945782ms","remote":"127.0.0.1:40732","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-19T20:04:13.569028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.255548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-19T20:04:13.569095Z","caller":"traceutil/trace.go:171","msg":"trace[554374493] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1359; }","duration":"831.366831ms","start":"2026-05-19T20:04:12.73771Z","end":"2026-05-19T20:04:13.569077Z","steps":["trace[554374493] 'agreement among raft nodes before linearized reading' (duration: 831.179706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:13.569134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.106549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-19T20:04:13.569194Z","caller":"traceutil/trace.go:171","msg":"trace[785515096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1359; }","duration":"112.190213ms","start":"2026-05-19T20:04:13.456988Z","end":"2026-05-19T20:04:13.569179Z","steps":["trace[785515096] 'agreement among raft nodes before linearized reading' (duration: 112.071729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:13.569031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.199173ms","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-19T20:04:13.569255Z","caller":"traceutil/trace.go:171","msg":"trace[1510465259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1359; }","duration":"415.43794ms","start":"2026-05-19T20:04:13.153804Z","end":"2026-05-19T20:04:13.569242Z","steps":["trace[1510465259] 'agreement among raft nodes before linearized reading' (duration: 415.109191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:13.569303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:13.153732Z","time spent":"415.558903ms","remote":"127.0.0.1:40732","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-19T20:04:13.56935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.587343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T20:04:13.569403Z","caller":"traceutil/trace.go:171","msg":"trace[1603403552] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1359; }","duration":"115.666564ms","start":"2026-05-19T20:04:13.453723Z","end":"2026-05-19T20:04:13.56939Z","steps":["trace[1603403552] 'agreement among raft nodes before linearized reading' (duration: 115.585572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:13.569148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:12.73769Z","time spent":"831.445494ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T20:04:13.569544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.467366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:04:13.569605Z","caller":"traceutil/trace.go:171","msg":"trace[1184251741] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1359; }","duration":"185.569959ms","start":"2026-05-19T20:04:13.38402Z","end":"2026-05-19T20:04:13.56959Z","steps":["trace[1184251741] 'agreement among raft nodes before linearized reading' (duration: 185.441866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:32.152249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.298776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-19T20:04:32.152456Z","caller":"traceutil/trace.go:171","msg":"trace[346059922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1446; }","duration":"268.565983ms","start":"2026-05-19T20:04:31.883872Z","end":"2026-05-19T20:04:32.152438Z","steps":["trace[346059922] 'range keys from in-memory index tree' (duration: 268.06849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:32.78986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567156047392829,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T20:04:33.29044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567156047392829,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T20:04:33.79088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567156047392829,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T20:04:34.160195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871317051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T20:04:34.160259Z","caller":"traceutil/trace.go:171","msg":"trace[698149629] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.871436594s","start":"2026-05-19T20:04:32.288806Z","end":"2026-05-19T20:04:34.160242Z","steps":["trace[698149629] 'agreement among raft nodes before linearized reading' (duration: 1.871345662s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:34.160298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:32.288791Z","time spent":"1.871495756s","remote":"127.0.0.1:40502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/19 20:04:34 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T20:04:34.303548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567156047392829,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T20:04:37.603426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:32.288787Z","time spent":"5.314632197s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-19T20:04:37.603933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.055556535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-19T20:04:37.603992Z","caller":"traceutil/trace.go:171","msg":"trace[1086477146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.055652328s","start":"2026-05-19T20:04:32.548324Z","end":"2026-05-19T20:04:37.603976Z","steps":["trace[1086477146] 'agreement among raft nodes before linearized reading' (duration: 5.055582316s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.604025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:32.54831Z","time spent":"5.055708069s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/05/19 20:04:37 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-19T20:04:37.604091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567156047392829,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-19T20:04:37.996208Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.70732404s","expected-duration":"1s"} {"level":"info","ts":"2026-05-19T20:04:37.997993Z","caller":"traceutil/trace.go:171","msg":"trace[758745576] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"4.367148985s","start":"2026-05-19T20:04:33.630824Z","end":"2026-05-19T20:04:37.997973Z","steps":["trace[758745576] 'process raft request' (duration: 4.366991111s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.998154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:33.630801Z","time spent":"4.367283849s","remote":"127.0.0.1:40732","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-19T20:04:37.998351Z","caller":"traceutil/trace.go:171","msg":"trace[1797320526] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"375.964279ms","start":"2026-05-19T20:04:37.622368Z","end":"2026-05-19T20:04:37.998333Z","steps":["trace[1797320526] 'process raft request' (duration: 375.557148ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:37.998369Z","caller":"traceutil/trace.go:171","msg":"trace[1622554088] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1504; }","duration":"5.709493627s","start":"2026-05-19T20:04:32.288842Z","end":"2026-05-19T20:04:37.998336Z","steps":["trace[1622554088] 'read index received' (duration: 5.707670038s)","trace[1622554088] 'applied index is now lower than readState.Index' (duration: 1.822929ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:04:37.998421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:37.622349Z","time spent":"376.0285ms","remote":"127.0.0.1:40556","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-19T20:04:37.998511Z","caller":"traceutil/trace.go:171","msg":"trace[1451606006] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"165.212953ms","start":"2026-05-19T20:04:37.833289Z","end":"2026-05-19T20:04:37.998502Z","steps":["trace[1451606006] 'process raft request' (duration: 164.982356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.998645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.593234275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-19T20:04:37.998667Z","caller":"traceutil/trace.go:171","msg":"trace[1454087412] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1450; }","duration":"5.593311047s","start":"2026-05-19T20:04:32.405349Z","end":"2026-05-19T20:04:37.99866Z","steps":["trace[1454087412] 'agreement among raft nodes before linearized reading' (duration: 5.593257546s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.998689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:32.405328Z","time spent":"5.593355199s","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-19T20:04:37.998702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.344899737s","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-19T20:04:37.998705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.201467809s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T20:04:37.998728Z","caller":"traceutil/trace.go:171","msg":"trace[245086426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1450; }","duration":"4.34497584s","start":"2026-05-19T20:04:33.653745Z","end":"2026-05-19T20:04:37.99872Z","steps":["trace[245086426] 'agreement among raft nodes before linearized reading' (duration: 4.344902698s)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:37.998739Z","caller":"traceutil/trace.go:171","msg":"trace[1359650144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1450; }","duration":"4.201527181s","start":"2026-05-19T20:04:33.797202Z","end":"2026-05-19T20:04:37.998729Z","steps":["trace[1359650144] 'agreement among raft nodes before linearized reading' (duration: 4.201412928s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.998752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:33.653729Z","time spent":"4.34501814s","remote":"127.0.0.1:40732","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-19T20:04:37.998767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:33.79719Z","time spent":"4.201568082s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T20:04:37.998851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.016991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T20:04:37.998863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.387854751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-19T20:04:37.998873Z","caller":"traceutil/trace.go:171","msg":"trace[1096771108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1450; }","duration":"373.037991ms","start":"2026-05-19T20:04:37.625828Z","end":"2026-05-19T20:04:37.998866Z","steps":["trace[1096771108] 'agreement among raft nodes before linearized reading' (duration: 373.003ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:37.998894Z","caller":"traceutil/trace.go:171","msg":"trace[1382913655] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1450; }","duration":"4.387904273s","start":"2026-05-19T20:04:33.610974Z","end":"2026-05-19T20:04:37.998879Z","steps":["trace[1382913655] 'agreement among raft nodes before linearized reading' (duration: 4.387823861s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.998896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:37.625801Z","time spent":"373.089843ms","remote":"127.0.0.1:40496","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-19T20:04:37.998915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:33.610959Z","time spent":"4.387949843s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T20:04:37.998964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.34008372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-19T20:04:37.998952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.465528562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T20:04:37.998989Z","caller":"traceutil/trace.go:171","msg":"trace[1578675360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1450; }","duration":"4.340154083s","start":"2026-05-19T20:04:33.658828Z","end":"2026-05-19T20:04:37.998982Z","steps":["trace[1578675360] 'agreement among raft nodes before linearized reading' (duration: 4.340099621s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.999008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:33.658817Z","time spent":"4.340187214s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T20:04:37.998915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.28294923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-19T20:04:37.99901Z","caller":"traceutil/trace.go:171","msg":"trace[1645783844] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1450; }","duration":"5.465632036s","start":"2026-05-19T20:04:32.533367Z","end":"2026-05-19T20:04:37.998999Z","steps":["trace[1645783844] 'agreement among raft nodes before linearized reading' (duration: 5.46545577s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.99904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.049605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-19T20:04:37.999042Z","caller":"traceutil/trace.go:171","msg":"trace[1254094963] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:1450; }","duration":"4.283113096s","start":"2026-05-19T20:04:33.715922Z","end":"2026-05-19T20:04:37.999035Z","steps":["trace[1254094963] 'agreement among raft nodes before linearized reading' (duration: 4.28294751s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.999062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:32.533351Z","time spent":"5.465705838s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-19T20:04:37.99906Z","caller":"traceutil/trace.go:171","msg":"trace[2080147035] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1450; }","duration":"395.090065ms","start":"2026-05-19T20:04:37.603964Z","end":"2026-05-19T20:04:37.999054Z","steps":["trace[2080147035] 'agreement among raft nodes before linearized reading' (duration: 395.037304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.999083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:37.603952Z","time spent":"395.126737ms","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T20:04:37.999137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.340353108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-19T20:04:37.999156Z","caller":"traceutil/trace.go:171","msg":"trace[1233457342] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1450; }","duration":"4.340391058s","start":"2026-05-19T20:04:33.658759Z","end":"2026-05-19T20:04:37.99915Z","steps":["trace[1233457342] 'agreement among raft nodes before linearized reading' (duration: 4.340338267s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.999171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.238679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T20:04:37.999187Z","caller":"traceutil/trace.go:171","msg":"trace[1721553588] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1450; }","duration":"395.268019ms","start":"2026-05-19T20:04:37.603915Z","end":"2026-05-19T20:04:37.999183Z","steps":["trace[1721553588] 'agreement among raft nodes before linearized reading' (duration: 395.235218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.999173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:33.658749Z","time spent":"4.34041942s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-19T20:04:37.999321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.404123ms","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-19T20:04:37.999342Z","caller":"traceutil/trace.go:171","msg":"trace[1801957205] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1450; }","duration":"395.452194ms","start":"2026-05-19T20:04:37.603882Z","end":"2026-05-19T20:04:37.999335Z","steps":["trace[1801957205] 'agreement among raft nodes before linearized reading' (duration: 395.417423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.999361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:37.603873Z","time spent":"395.481946ms","remote":"127.0.0.1:50110","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-19T20:04:37.999066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:33.715902Z","time spent":"4.283157407s","remote":"127.0.0.1:40516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-05-19T20:04:37.999495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:37.60391Z","time spent":"395.574018ms","remote":"127.0.0.1:40674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-05-19T20:04:37.999502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.023294214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-19T20:04:37.999605Z","caller":"traceutil/trace.go:171","msg":"trace[852107354] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1450; }","duration":"4.023436958s","start":"2026-05-19T20:04:33.97616Z","end":"2026-05-19T20:04:37.999597Z","steps":["trace[852107354] 'agreement among raft nodes before linearized reading' (duration: 4.023298834s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.999626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:33.976136Z","time spent":"4.023483678s","remote":"127.0.0.1:40732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-19T20:04:37.999849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.002964427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:75033"} {"level":"info","ts":"2026-05-19T20:04:37.999881Z","caller":"traceutil/trace.go:171","msg":"trace[1181237742] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1450; }","duration":"5.003037988s","start":"2026-05-19T20:04:32.996835Z","end":"2026-05-19T20:04:37.999873Z","steps":["trace[1181237742] 'agreement among raft nodes before linearized reading' (duration: 5.002294619s)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:04:37.999906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T20:04:32.996812Z","time spent":"5.00308922s","remote":"127.0.0.1:40540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":75056,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-05-19T20:04:50.225932Z","caller":"traceutil/trace.go:171","msg":"trace[381179918] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"114.884754ms","start":"2026-05-19T20:04:50.111027Z","end":"2026-05-19T20:04:50.225912Z","steps":["trace[381179918] 'process raft request' (duration: 75.156974ms)","trace[381179918] 'compare' (duration: 39.596177ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:04:50.226276Z","caller":"traceutil/trace.go:171","msg":"trace[230007535] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"114.754081ms","start":"2026-05-19T20:04:50.111497Z","end":"2026-05-19T20:04:50.226251Z","steps":["trace[230007535] 'process raft request' (duration: 114.379521ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:50.226411Z","caller":"traceutil/trace.go:171","msg":"trace[1915072036] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"114.791292ms","start":"2026-05-19T20:04:50.111594Z","end":"2026-05-19T20:04:50.226385Z","steps":["trace[1915072036] 'process raft request' (duration: 114.501724ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:50.226717Z","caller":"traceutil/trace.go:171","msg":"trace[888995030] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"115.003437ms","start":"2026-05-19T20:04:50.111598Z","end":"2026-05-19T20:04:50.226601Z","steps":["trace[888995030] 'process raft request' (duration: 114.635458ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:04:51.80258Z","caller":"traceutil/trace.go:171","msg":"trace[648761637] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"134.012978ms","start":"2026-05-19T20:04:51.668524Z","end":"2026-05-19T20:04:51.802537Z","steps":["trace[648761637] 'process raft request' (duration: 132.16016ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:05:42.688886Z","caller":"traceutil/trace.go:171","msg":"trace[1941177724] linearizableReadLoop","detail":"{readStateIndex:1820; appliedIndex:1818; }","duration":"154.899212ms","start":"2026-05-19T20:05:42.533956Z","end":"2026-05-19T20:05:42.688855Z","steps":["trace[1941177724] 'read index received' (duration: 40.654257ms)","trace[1941177724] 'applied index is now lower than readState.Index' (duration: 114.243855ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:05:42.688956Z","caller":"traceutil/trace.go:171","msg":"trace[738385703] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"155.9895ms","start":"2026-05-19T20:05:42.532917Z","end":"2026-05-19T20:05:42.688907Z","steps":["trace[738385703] 'process raft request' (duration: 140.484274ms)","trace[738385703] 'compare' (duration: 15.23663ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:05:42.689Z","caller":"traceutil/trace.go:171","msg":"trace[487889452] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"154.659215ms","start":"2026-05-19T20:05:42.534301Z","end":"2026-05-19T20:05:42.68896Z","steps":["trace[487889452] 'process raft request' (duration: 154.506402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:05:42.689246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.261391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-19T20:05:42.689295Z","caller":"traceutil/trace.go:171","msg":"trace[190378206] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1749; }","duration":"155.344593ms","start":"2026-05-19T20:05:42.533938Z","end":"2026-05-19T20:05:42.689283Z","steps":["trace[190378206] 'agreement among raft nodes before linearized reading' (duration: 155.147947ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:06:55.207088Z","caller":"traceutil/trace.go:171","msg":"trace[1310504961] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"130.811621ms","start":"2026-05-19T20:06:55.076248Z","end":"2026-05-19T20:06:55.20706Z","steps":["trace[1310504961] 'process raft request' (duration: 130.609595ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:06:55.345674Z","caller":"traceutil/trace.go:171","msg":"trace[262589451] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2169; }","duration":"186.11633ms","start":"2026-05-19T20:06:55.159527Z","end":"2026-05-19T20:06:55.345644Z","steps":["trace[262589451] 'read index received' (duration: 47.370645ms)","trace[262589451] 'applied index is now lower than readState.Index' (duration: 138.744495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T20:06:55.345982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.410907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-05-19T20:06:55.345958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.110971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:06:55.346034Z","caller":"traceutil/trace.go:171","msg":"trace[731016156] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2084; }","duration":"186.52809ms","start":"2026-05-19T20:06:55.159489Z","end":"2026-05-19T20:06:55.346017Z","steps":["trace[731016156] 'agreement among raft nodes before linearized reading' (duration: 186.329435ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:06:55.346058Z","caller":"traceutil/trace.go:171","msg":"trace[1388961581] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2084; }","duration":"104.219924ms","start":"2026-05-19T20:06:55.241814Z","end":"2026-05-19T20:06:55.346034Z","steps":["trace[1388961581] 'agreement among raft nodes before linearized reading' (duration: 104.06925ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:06:55.345857Z","caller":"traceutil/trace.go:171","msg":"trace[1475898728] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"267.644625ms","start":"2026-05-19T20:06:55.078161Z","end":"2026-05-19T20:06:55.345805Z","steps":["trace[1475898728] 'process raft request' (duration: 189.462814ms)","trace[1475898728] 'compare' (duration: 77.876653ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:08:02.917469Z","caller":"traceutil/trace.go:171","msg":"trace[1948157198] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"104.561926ms","start":"2026-05-19T20:08:02.812885Z","end":"2026-05-19T20:08:02.917447Z","steps":["trace[1948157198] 'process raft request' (duration: 104.428273ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:08:33.237251Z","caller":"traceutil/trace.go:171","msg":"trace[977354795] linearizableReadLoop","detail":"{readStateIndex:2670; appliedIndex:2669; }","duration":"168.463995ms","start":"2026-05-19T20:08:33.068764Z","end":"2026-05-19T20:08:33.237228Z","steps":["trace[977354795] 'read index received' (duration: 168.273111ms)","trace[977354795] 'applied index is now lower than readState.Index' (duration: 189.854µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T20:08:33.237312Z","caller":"traceutil/trace.go:171","msg":"trace[1738723895] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"193.39806ms","start":"2026-05-19T20:08:33.043882Z","end":"2026-05-19T20:08:33.23728Z","steps":["trace[1738723895] 'process raft request' (duration: 193.175055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:08:33.237436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.646569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:08:33.237487Z","caller":"traceutil/trace.go:171","msg":"trace[283539415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2562; }","duration":"168.735232ms","start":"2026-05-19T20:08:33.068736Z","end":"2026-05-19T20:08:33.237471Z","steps":["trace[283539415] 'agreement among raft nodes before linearized reading' (duration: 168.63761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:08:33.244612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.446321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 ","response":"range_response_count:2 size:1230"} {"level":"info","ts":"2026-05-19T20:08:33.244682Z","caller":"traceutil/trace.go:171","msg":"trace[1692130673] range","detail":"{range_begin:/registry/rolebindings/kube-public/; range_end:/registry/rolebindings/kube-public0; response_count:2; response_revision:2563; }","duration":"124.546025ms","start":"2026-05-19T20:08:33.120122Z","end":"2026-05-19T20:08:33.244668Z","steps":["trace[1692130673] 'agreement among raft nodes before linearized reading' (duration: 124.423271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:08:33.244727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.819375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/cluster-info\" ","response":"range_response_count:1 size:2177"} {"level":"warn","ts":"2026-05-19T20:08:33.244765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.713729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-05-19T20:08:33.24481Z","caller":"traceutil/trace.go:171","msg":"trace[600317078] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"162.341048ms","start":"2026-05-19T20:08:33.082458Z","end":"2026-05-19T20:08:33.244799Z","steps":["trace[600317078] 'process raft request' (duration: 159.786933ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:08:33.244801Z","caller":"traceutil/trace.go:171","msg":"trace[567682821] range","detail":"{range_begin:/registry/configmaps/kube-public/cluster-info; range_end:; response_count:1; response_revision:2563; }","duration":"119.927518ms","start":"2026-05-19T20:08:33.124856Z","end":"2026-05-19T20:08:33.244784Z","steps":["trace[567682821] 'agreement among raft nodes before linearized reading' (duration: 119.771214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T20:08:33.244646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.387859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T20:08:33.24489Z","caller":"traceutil/trace.go:171","msg":"trace[1283469785] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:2563; }","duration":"169.633894ms","start":"2026-05-19T20:08:33.075246Z","end":"2026-05-19T20:08:33.24488Z","steps":["trace[1283469785] 'agreement among raft nodes before linearized reading' (duration: 169.372828ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T20:08:33.244891Z","caller":"traceutil/trace.go:171","msg":"trace[914510784] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:2563; }","duration":"122.803861ms","start":"2026-05-19T20:08:33.122027Z","end":"2026-05-19T20:08:33.24483Z","steps":["trace[914510784] 'agreement among raft nodes before linearized reading' (duration: 122.646607ms)"],"step_count":1}