{"level":"warn","ts":"2026-02-27T18:20:49.677575Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T18:20:49.677692Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-02-27T18:20:49.677781Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T18:20:49.677792Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-02-27T18:20:49.677814Z","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-02-27T18:20:49.678647Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-02-27T18:20:49.678744Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-02-27T18:20:49.684115Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.817196ms"} {"level":"info","ts":"2026-02-27T18:20:49.690793Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-02-27T18:20:49.690954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T18:20:49.690988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-02-27T18:20:49.691008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T18:20:49.691021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-02-27T18:20:49.691076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-02-27T18:20:49.701252Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T18:20:49.703428Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T18:20:49.704613Z","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-02-27T18:20:49.70731Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T18:20:49.707473Z","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-02-27T18:20:49.707541Z","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-02-27T18:20:49.707566Z","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-02-27T18:20:49.707559Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T18:20:49.710772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-02-27T18:20:49.710909Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-02-27T18:20:49.712498Z","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-02-27T18:20:49.712629Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-02-27T18:20:49.712659Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-02-27T18:20:49.712845Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T18:20:49.712913Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T18:20:50.491575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T18:20:50.491681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T18:20:50.491763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-02-27T18:20:50.49183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-02-27T18:20:50.491863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-02-27T18:20:50.491917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-02-27T18:20:50.491942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-02-27T18:20:50.492838Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T18:20:50.492903Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T18:20:50.493055Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:20:50.493162Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T18:20:50.493224Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T18:20:50.492957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T18:20:50.493874Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:20:50.493946Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:20:50.494061Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:20:50.495921Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T18:20:50.496703Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-02-27T18:21:00.025901Z","caller":"traceutil/trace.go:171","msg":"trace[1983610131] linearizableReadLoop","detail":"{readStateIndex:356; appliedIndex:355; }","duration":"181.780243ms","start":"2026-02-27T18:20:59.844089Z","end":"2026-02-27T18:21:00.025869Z","steps":["trace[1983610131] 'read index received' (duration: 94.22513ms)","trace[1983610131] 'applied index is now lower than readState.Index' (duration: 87.553943ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:00.026113Z","caller":"traceutil/trace.go:171","msg":"trace[1880434318] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"212.464077ms","start":"2026-02-27T18:20:59.813521Z","end":"2026-02-27T18:21:00.025985Z","steps":["trace[1880434318] 'process raft request' (duration: 124.805091ms)","trace[1880434318] 'compare' (duration: 87.414581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:00.026189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.070761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-27T18:21:00.026338Z","caller":"traceutil/trace.go:171","msg":"trace[1355053255] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:346; }","duration":"132.260877ms","start":"2026-02-27T18:20:59.894035Z","end":"2026-02-27T18:21:00.026296Z","steps":["trace[1355053255] 'agreement among raft nodes before linearized reading' (duration: 132.071552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:00.026123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.003829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-27T18:21:00.026428Z","caller":"traceutil/trace.go:171","msg":"trace[348122055] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:346; }","duration":"182.348897ms","start":"2026-02-27T18:20:59.844063Z","end":"2026-02-27T18:21:00.026412Z","steps":["trace[348122055] 'agreement among raft nodes before linearized reading' (duration: 181.983528ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:04.854209Z","caller":"traceutil/trace.go:171","msg":"trace[217155483] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"129.367112ms","start":"2026-02-27T18:21:04.724822Z","end":"2026-02-27T18:21:04.854189Z","steps":["trace[217155483] 'process raft request' (duration: 129.258249ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:05.412122Z","caller":"traceutil/trace.go:171","msg":"trace[1364006986] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"216.65601ms","start":"2026-02-27T18:21:05.195444Z","end":"2026-02-27T18:21:05.4121Z","steps":["trace[1364006986] 'process raft request' (duration: 211.686781ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:05.571148Z","caller":"traceutil/trace.go:171","msg":"trace[1232660806] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"121.411297ms","start":"2026-02-27T18:21:05.449688Z","end":"2026-02-27T18:21:05.571099Z","steps":["trace[1232660806] 'process raft request' (duration: 97.622914ms)","trace[1232660806] 'compare' (duration: 23.531998ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:07.60211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.3691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:21:07.602288Z","caller":"traceutil/trace.go:171","msg":"trace[1826190035] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"311.81838ms","start":"2026-02-27T18:21:07.290452Z","end":"2026-02-27T18:21:07.602271Z","steps":["trace[1826190035] 'process raft request' (duration: 177.624372ms)","trace[1826190035] 'compare' (duration: 132.969621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:07.602364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:07.290417Z","time spent":"311.909692ms","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:21:07.602463Z","caller":"traceutil/trace.go:171","msg":"trace[1550661269] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:443; }","duration":"310.42243ms","start":"2026-02-27T18:21:07.292024Z","end":"2026-02-27T18:21:07.602446Z","steps":["trace[1550661269] 'read index received' (duration: 176.063868ms)","trace[1550661269] 'applied index is now lower than readState.Index' (duration: 134.357702ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:07.60251Z","caller":"traceutil/trace.go:171","msg":"trace[968669869] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"311.076585ms","start":"2026-02-27T18:21:07.291405Z","end":"2026-02-27T18:21:07.602481Z","steps":["trace[968669869] 'process raft request' (duration: 310.812849ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:07.602534Z","caller":"traceutil/trace.go:171","msg":"trace[2134445267] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"306.292838ms","start":"2026-02-27T18:21:07.296216Z","end":"2026-02-27T18:21:07.602508Z","steps":["trace[2134445267] 'process raft request' (duration: 306.168146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:07.60259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.556783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"warn","ts":"2026-02-27T18:21:07.602613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.756443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-02-27T18:21:07.602621Z","caller":"traceutil/trace.go:171","msg":"trace[1027178198] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:434; }","duration":"310.620495ms","start":"2026-02-27T18:21:07.29199Z","end":"2026-02-27T18:21:07.602611Z","steps":["trace[1027178198] 'agreement among raft nodes before linearized reading' (duration: 310.540453ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:07.602635Z","caller":"traceutil/trace.go:171","msg":"trace[740382094] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:434; }","duration":"308.793464ms","start":"2026-02-27T18:21:07.293835Z","end":"2026-02-27T18:21:07.602628Z","steps":["trace[740382094] 'agreement among raft nodes before linearized reading' (duration: 308.749223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:07.602652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:07.293828Z","time spent":"308.820124ms","remote":"127.0.0.1:42272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4217,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-27T18:21:07.602653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:07.291978Z","time spent":"310.668365ms","remote":"127.0.0.1:42290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3832,"request content":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" "} {"level":"warn","ts":"2026-02-27T18:21:07.602873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:07.296207Z","time spent":"306.371471ms","remote":"127.0.0.1:41996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:21:07.602925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:07.291391Z","time spent":"311.186707ms","remote":"127.0.0.1:41996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:21:12.770994Z","caller":"traceutil/trace.go:171","msg":"trace[1932573488] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:475; }","duration":"406.413349ms","start":"2026-02-27T18:21:12.364549Z","end":"2026-02-27T18:21:12.770962Z","steps":["trace[1932573488] 'read index received' (duration: 406.191414ms)","trace[1932573488] 'applied index is now lower than readState.Index' (duration: 220.595µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:12.77113Z","caller":"traceutil/trace.go:171","msg":"trace[2057357006] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"446.202212ms","start":"2026-02-27T18:21:12.324901Z","end":"2026-02-27T18:21:12.771104Z","steps":["trace[2057357006] 'process raft request' (duration: 445.869155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:12.771195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.620293ms","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-02-27T18:21:12.77126Z","caller":"traceutil/trace.go:171","msg":"trace[1254812036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"406.722715ms","start":"2026-02-27T18:21:12.364517Z","end":"2026-02-27T18:21:12.77124Z","steps":["trace[1254812036] 'agreement among raft nodes before linearized reading' (duration: 406.600392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:12.771305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:12.364507Z","time spent":"406.789156ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T18:21:12.771324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:12.324885Z","time spent":"446.357636ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:21:12.810087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.380085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T18:21:12.810195Z","caller":"traceutil/trace.go:171","msg":"trace[1095811322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"445.526688ms","start":"2026-02-27T18:21:12.364642Z","end":"2026-02-27T18:21:12.810169Z","steps":["trace[1095811322] 'agreement among raft nodes before linearized reading' (duration: 445.362375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:12.810266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:12.364619Z","time spent":"445.62074ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T18:21:12.810418Z","caller":"traceutil/trace.go:171","msg":"trace[532291036] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"115.16285ms","start":"2026-02-27T18:21:12.695228Z","end":"2026-02-27T18:21:12.81039Z","steps":["trace[532291036] 'process raft request' (duration: 114.664959ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:17.79312Z","caller":"traceutil/trace.go:171","msg":"trace[892606049] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"109.938606ms","start":"2026-02-27T18:21:17.683146Z","end":"2026-02-27T18:21:17.793084Z","steps":["trace[892606049] 'process raft request' (duration: 109.64296ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:20.622005Z","caller":"traceutil/trace.go:171","msg":"trace[12176223] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"820.942636ms","start":"2026-02-27T18:21:19.80104Z","end":"2026-02-27T18:21:20.621983Z","steps":["trace[12176223] 'process raft request' (duration: 820.724981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:20.622225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:19.801025Z","time spent":"821.11988ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:21:23.07858Z","caller":"traceutil/trace.go:171","msg":"trace[1589862653] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"448.012231ms","start":"2026-02-27T18:21:22.630534Z","end":"2026-02-27T18:21:23.078547Z","steps":["trace[1589862653] 'process raft request' (duration: 447.811997ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:23.078936Z","caller":"traceutil/trace.go:171","msg":"trace[1698369558] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:535; }","duration":"243.95357ms","start":"2026-02-27T18:21:22.834964Z","end":"2026-02-27T18:21:23.078917Z","steps":["trace[1698369558] 'read index received' (duration: 243.9435ms)","trace[1698369558] 'applied index is now lower than readState.Index' (duration: 8.56µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:23.079263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.300509ms","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-02-27T18:21:23.079333Z","caller":"traceutil/trace.go:171","msg":"trace[1624322648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"244.409431ms","start":"2026-02-27T18:21:22.834907Z","end":"2026-02-27T18:21:23.079316Z","steps":["trace[1624322648] 'agreement among raft nodes before linearized reading' (duration: 244.139955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:23.078834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:22.630512Z","time spent":"448.162895ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:21:23.240433Z","caller":"traceutil/trace.go:171","msg":"trace[372704434] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"277.119138ms","start":"2026-02-27T18:21:22.963263Z","end":"2026-02-27T18:21:23.240382Z","steps":["trace[372704434] 'process raft request' (duration: 261.574187ms)","trace[372704434] 'compare' (duration: 14.737243ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:23.24055Z","caller":"traceutil/trace.go:171","msg":"trace[1116554833] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"156.13816ms","start":"2026-02-27T18:21:23.084367Z","end":"2026-02-27T18:21:23.240505Z","steps":["trace[1116554833] 'process raft request' (duration: 156.025587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:23.240796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.027329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T18:21:23.240592Z","caller":"traceutil/trace.go:171","msg":"trace[1130399325] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"161.547079ms","start":"2026-02-27T18:21:23.079012Z","end":"2026-02-27T18:21:23.240559Z","steps":["trace[1130399325] 'read index received' (duration: 145.764753ms)","trace[1130399325] 'applied index is now lower than readState.Index' (duration: 15.780316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:23.240828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.102778ms","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-02-27T18:21:23.24086Z","caller":"traceutil/trace.go:171","msg":"trace[757929932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"365.160022ms","start":"2026-02-27T18:21:22.875683Z","end":"2026-02-27T18:21:23.240843Z","steps":["trace[757929932] 'agreement among raft nodes before linearized reading' (duration: 364.965178ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:23.240887Z","caller":"traceutil/trace.go:171","msg":"trace[1005536623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"369.19857ms","start":"2026-02-27T18:21:22.871673Z","end":"2026-02-27T18:21:23.240871Z","steps":["trace[1005536623] 'agreement among raft nodes before linearized reading' (duration: 368.978556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:23.240903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:22.87565Z","time spent":"365.244334ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T18:21:23.24093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:22.871659Z","time spent":"369.262362ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T18:21:29.984017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.336971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:21:29.984123Z","caller":"traceutil/trace.go:171","msg":"trace[595285504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"261.506796ms","start":"2026-02-27T18:21:29.722594Z","end":"2026-02-27T18:21:29.984101Z","steps":["trace[595285504] 'range keys from in-memory index tree' (duration: 261.186228ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:30.651659Z","caller":"traceutil/trace.go:171","msg":"trace[1032792665] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"396.974731ms","start":"2026-02-27T18:21:30.254665Z","end":"2026-02-27T18:21:30.65164Z","steps":["trace[1032792665] 'process raft request' (duration: 396.853749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:30.651794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:30.254652Z","time spent":"397.069423ms","remote":"127.0.0.1:41996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:21:30.984252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.511252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:21:30.984342Z","caller":"traceutil/trace.go:171","msg":"trace[58665451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"259.646905ms","start":"2026-02-27T18:21:30.72467Z","end":"2026-02-27T18:21:30.984317Z","steps":["trace[58665451] 'range keys from in-memory index tree' (duration: 259.41732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:32.024701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.295524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-27T18:21:32.024861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.510625ms","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-02-27T18:21:32.024882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.577322ms","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-02-27T18:21:32.024921Z","caller":"traceutil/trace.go:171","msg":"trace[1577594981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"723.627298ms","start":"2026-02-27T18:21:31.301276Z","end":"2026-02-27T18:21:32.024904Z","steps":["trace[1577594981] 'range keys from in-memory index tree' (duration: 723.349593ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:32.024971Z","caller":"traceutil/trace.go:171","msg":"trace[2056859164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"720.685224ms","start":"2026-02-27T18:21:31.304266Z","end":"2026-02-27T18:21:32.024951Z","steps":["trace[2056859164] 'range keys from in-memory index tree' (duration: 720.450529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:32.025021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:31.304235Z","time spent":"720.774635ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T18:21:32.025019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:31.301256Z","time spent":"723.749371ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T18:21:32.024981Z","caller":"traceutil/trace.go:171","msg":"trace[533347315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"714.527808ms","start":"2026-02-27T18:21:31.310353Z","end":"2026-02-27T18:21:32.024881Z","steps":["trace[533347315] 'range keys from in-memory index tree' (duration: 714.151601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:32.025141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:31.310341Z","time spent":"714.783395ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T18:21:32.504818Z","caller":"traceutil/trace.go:171","msg":"trace[696259383] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"475.817163ms","start":"2026-02-27T18:21:32.028973Z","end":"2026-02-27T18:21:32.50479Z","steps":["trace[696259383] 'process raft request' (duration: 475.691181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:32.504969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:32.028945Z","time spent":"475.943187ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:21:32.726468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.712015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:21:32.726835Z","caller":"traceutil/trace.go:171","msg":"trace[1497193229] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"697.760401ms","start":"2026-02-27T18:21:32.029043Z","end":"2026-02-27T18:21:32.726804Z","steps":["trace[1497193229] 'process raft request' (duration: 590.619676ms)","trace[1497193229] 'compare' (duration: 106.49123ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:32.726922Z","caller":"traceutil/trace.go:171","msg":"trace[603778980] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"681.217837ms","start":"2026-02-27T18:21:32.045686Z","end":"2026-02-27T18:21:32.726904Z","steps":["trace[603778980] 'process raft request' (duration: 680.887019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:32.727252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:32.029009Z","time spent":"697.893793ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:21:32.727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:32.045658Z","time spent":"681.304949ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:21:33.267154Z","caller":"traceutil/trace.go:171","msg":"trace[269543003] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"102.438599ms","start":"2026-02-27T18:21:33.164697Z","end":"2026-02-27T18:21:33.267135Z","steps":["trace[269543003] 'process raft request' (duration: 102.283936ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:34.744671Z","caller":"traceutil/trace.go:171","msg":"trace[729925599] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"235.608046ms","start":"2026-02-27T18:21:34.509043Z","end":"2026-02-27T18:21:34.744651Z","steps":["trace[729925599] 'read index received' (duration: 235.478104ms)","trace[729925599] 'applied index is now lower than readState.Index' (duration: 128.922µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:34.744869Z","caller":"traceutil/trace.go:171","msg":"trace[2077098789] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"330.792388ms","start":"2026-02-27T18:21:34.413963Z","end":"2026-02-27T18:21:34.744755Z","steps":["trace[2077098789] 'process raft request' (duration: 330.564543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:34.744922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.867612ms","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-02-27T18:21:34.744986Z","caller":"traceutil/trace.go:171","msg":"trace[1532076014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"235.978224ms","start":"2026-02-27T18:21:34.50899Z","end":"2026-02-27T18:21:34.744969Z","steps":["trace[1532076014] 'agreement among raft nodes before linearized reading' (duration: 235.7842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:34.74506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:34.41393Z","time spent":"331.038172ms","remote":"127.0.0.1:41852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:21:34.749038Z","caller":"traceutil/trace.go:171","msg":"trace[1511234126] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"138.777958ms","start":"2026-02-27T18:21:34.610245Z","end":"2026-02-27T18:21:34.749023Z","steps":["trace[1511234126] 'process raft request' (duration: 138.003511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:43.484371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.336724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:21:43.484492Z","caller":"traceutil/trace.go:171","msg":"trace[1967192291] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"178.042292ms","start":"2026-02-27T18:21:43.306431Z","end":"2026-02-27T18:21:43.484473Z","steps":["trace[1967192291] 'process raft request' (duration: 34.481124ms)","trace[1967192291] 'compare' (duration: 143.222851ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:43.814457Z","caller":"traceutil/trace.go:171","msg":"trace[213388312] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"253.94296ms","start":"2026-02-27T18:21:43.560491Z","end":"2026-02-27T18:21:43.814434Z","steps":["trace[213388312] 'process raft request' (duration: 253.780755ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:49.490907Z","caller":"traceutil/trace.go:171","msg":"trace[745539943] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"109.249217ms","start":"2026-02-27T18:21:49.381634Z","end":"2026-02-27T18:21:49.490883Z","steps":["trace[745539943] 'process raft request' (duration: 109.098894ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:50.049067Z","caller":"traceutil/trace.go:171","msg":"trace[1420407730] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"323.94745ms","start":"2026-02-27T18:21:49.72509Z","end":"2026-02-27T18:21:50.049037Z","steps":["trace[1420407730] 'read index received' (duration: 240.837097ms)","trace[1420407730] 'applied index is now lower than readState.Index' (duration: 83.109033ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:50.049179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.078473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:21:50.049177Z","caller":"traceutil/trace.go:171","msg":"trace[684121842] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"533.058342ms","start":"2026-02-27T18:21:49.516042Z","end":"2026-02-27T18:21:50.0491Z","steps":["trace[684121842] 'process raft request' (duration: 449.934508ms)","trace[684121842] 'compare' (duration: 82.903309ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:50.049244Z","caller":"traceutil/trace.go:171","msg":"trace[1457872949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"324.173405ms","start":"2026-02-27T18:21:49.725051Z","end":"2026-02-27T18:21:50.049224Z","steps":["trace[1457872949] 'agreement among raft nodes before linearized reading' (duration: 324.071663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:50.049412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:49.725036Z","time spent":"324.367939ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T18:21:50.04947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:49.516022Z","time spent":"533.300257ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:21:50.448195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.68476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T18:21:50.448312Z","caller":"traceutil/trace.go:171","msg":"trace[1867069440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"174.838684ms","start":"2026-02-27T18:21:50.27342Z","end":"2026-02-27T18:21:50.448258Z","steps":["trace[1867069440] 'range keys from in-memory index tree' (duration: 174.466654ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:50.781636Z","caller":"traceutil/trace.go:171","msg":"trace[1124962875] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"329.288955ms","start":"2026-02-27T18:21:50.452323Z","end":"2026-02-27T18:21:50.781612Z","steps":["trace[1124962875] 'process raft request' (duration: 329.149192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:50.78198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:50.452256Z","time spent":"329.647084ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:21:53.347641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742996989059335,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T18:21:53.690356Z","caller":"traceutil/trace.go:171","msg":"trace[685954869] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"843.235256ms","start":"2026-02-27T18:21:52.847102Z","end":"2026-02-27T18:21:53.690337Z","steps":["trace[685954869] 'read index received' (duration: 843.087713ms)","trace[685954869] 'applied index is now lower than readState.Index' (duration: 146.543µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:53.690455Z","caller":"traceutil/trace.go:171","msg":"trace[199485850] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"900.650645ms","start":"2026-02-27T18:21:52.78978Z","end":"2026-02-27T18:21:53.690431Z","steps":["trace[199485850] 'process raft request' (duration: 900.43543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:53.690564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.45115ms","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-02-27T18:21:53.69059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:52.789763Z","time spent":"900.748737ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:21:53.69061Z","caller":"traceutil/trace.go:171","msg":"trace[1527107964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"843.535732ms","start":"2026-02-27T18:21:52.847062Z","end":"2026-02-27T18:21:53.690598Z","steps":["trace[1527107964] 'agreement among raft nodes before linearized reading' (duration: 843.380399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:53.690638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:52.847042Z","time spent":"843.588683ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T18:21:54.653819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.343544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:21:54.654015Z","caller":"traceutil/trace.go:171","msg":"trace[1497960540] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"958.625095ms","start":"2026-02-27T18:21:53.695371Z","end":"2026-02-27T18:21:54.653996Z","steps":["trace[1497960540] 'process raft request' (duration: 958.549483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:54.654079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:53.695355Z","time spent":"958.694307ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:21:54.654186Z","caller":"traceutil/trace.go:171","msg":"trace[1354058000] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"1.047530594s","start":"2026-02-27T18:21:53.606556Z","end":"2026-02-27T18:21:54.654087Z","steps":["trace[1354058000] 'process raft request' (duration: 504.811182ms)","trace[1354058000] 'compare' (duration: 541.986056ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:54.654358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:53.606533Z","time spent":"1.047732208s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:21:54.654414Z","caller":"traceutil/trace.go:171","msg":"trace[698068830] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"963.990871ms","start":"2026-02-27T18:21:53.690411Z","end":"2026-02-27T18:21:54.654402Z","steps":["trace[698068830] 'read index received' (duration: 420.946503ms)","trace[698068830] 'applied index is now lower than readState.Index' (duration: 543.043208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:54.654527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355539311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T18:21:54.654556Z","caller":"traceutil/trace.go:171","msg":"trace[316087107] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:735; }","duration":"1.355608191s","start":"2026-02-27T18:21:53.298938Z","end":"2026-02-27T18:21:54.654546Z","steps":["trace[316087107] 'agreement among raft nodes before linearized reading' (duration: 1.35554785s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:54.654546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094047417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:21:54.654587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:53.298919Z","time spent":"1.355660243s","remote":"127.0.0.1:41976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-02-27T18:21:54.654623Z","caller":"traceutil/trace.go:171","msg":"trace[695833720] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:735; }","duration":"1.09415293s","start":"2026-02-27T18:21:53.56045Z","end":"2026-02-27T18:21:54.654603Z","steps":["trace[695833720] 'agreement among raft nodes before linearized reading' (duration: 1.094040307s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:54.654665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:53.560438Z","time spent":"1.094218231s","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-27T18:21:54.654678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094293353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-27T18:21:54.654746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.798893027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T18:21:54.654785Z","caller":"traceutil/trace.go:171","msg":"trace[1367279189] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:735; }","duration":"1.094412226s","start":"2026-02-27T18:21:53.560342Z","end":"2026-02-27T18:21:54.654754Z","steps":["trace[1367279189] 'agreement among raft nodes before linearized reading' (duration: 1.094102789s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:54.654826Z","caller":"traceutil/trace.go:171","msg":"trace[1568348195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"1.799014039s","start":"2026-02-27T18:21:52.85579Z","end":"2026-02-27T18:21:54.654804Z","steps":["trace[1568348195] 'agreement among raft nodes before linearized reading' (duration: 1.798756543s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:54.654814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.512116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-27T18:21:54.654842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:53.560278Z","time spent":"1.094553208s","remote":"127.0.0.1:41844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-02-27T18:21:54.654877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:52.855779Z","time spent":"1.799089451s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T18:21:54.654909Z","caller":"traceutil/trace.go:171","msg":"trace[1552419480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:735; }","duration":"504.648769ms","start":"2026-02-27T18:21:54.150224Z","end":"2026-02-27T18:21:54.654872Z","steps":["trace[1552419480] 'agreement among raft nodes before linearized reading' (duration: 504.439135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:54.65496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:54.150192Z","time spent":"504.751701ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T18:21:54.655176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.799417418s","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-02-27T18:21:54.655217Z","caller":"traceutil/trace.go:171","msg":"trace[9478332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"1.799488899s","start":"2026-02-27T18:21:52.855716Z","end":"2026-02-27T18:21:54.655205Z","steps":["trace[9478332] 'agreement among raft nodes before linearized reading' (duration: 1.798800745s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:54.655246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:52.855696Z","time spent":"1.79954331s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T18:21:55.109811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:54.657526Z","time spent":"452.28098ms","remote":"127.0.0.1:41844","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-27T18:21:55.397738Z","caller":"traceutil/trace.go:171","msg":"trace[1377619546] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"738.491994ms","start":"2026-02-27T18:21:54.659222Z","end":"2026-02-27T18:21:55.397714Z","steps":["trace[1377619546] 'process raft request' (duration: 691.273457ms)","trace[1377619546] 'compare' (duration: 46.988373ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:21:55.397812Z","caller":"traceutil/trace.go:171","msg":"trace[185953257] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:758; }","duration":"672.896389ms","start":"2026-02-27T18:21:54.724894Z","end":"2026-02-27T18:21:55.39779Z","steps":["trace[185953257] 'read index received' (duration: 384.878525ms)","trace[185953257] 'applied index is now lower than readState.Index' (duration: 288.016754ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:55.397849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:54.659189Z","time spent":"738.603667ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:21:55.397847Z","caller":"traceutil/trace.go:171","msg":"trace[700550481] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"286.943391ms","start":"2026-02-27T18:21:55.110892Z","end":"2026-02-27T18:21:55.397835Z","steps":["trace[700550481] 'process raft request' (duration: 286.860459ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:55.397826Z","caller":"traceutil/trace.go:171","msg":"trace[883116261] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"738.434984ms","start":"2026-02-27T18:21:54.659342Z","end":"2026-02-27T18:21:55.397777Z","steps":["trace[883116261] 'process raft request' (duration: 738.308231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:55.397964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.057163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:21:55.397986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:54.659314Z","time spent":"738.582766ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:21:55.398012Z","caller":"traceutil/trace.go:171","msg":"trace[958349734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"673.160955ms","start":"2026-02-27T18:21:54.724836Z","end":"2026-02-27T18:21:55.397997Z","steps":["trace[958349734] 'agreement among raft nodes before linearized reading' (duration: 673.034513ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:55.398052Z","caller":"traceutil/trace.go:171","msg":"trace[650474576] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"737.557495ms","start":"2026-02-27T18:21:54.660484Z","end":"2026-02-27T18:21:55.398041Z","steps":["trace[650474576] 'process raft request' (duration: 737.226238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:55.398084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:54.660466Z","time spent":"737.600216ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:21:55.398074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:54.724812Z","time spent":"673.247267ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T18:21:56.783693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.524187ms","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-02-27T18:21:56.783761Z","caller":"traceutil/trace.go:171","msg":"trace[465708547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"124.599808ms","start":"2026-02-27T18:21:56.659144Z","end":"2026-02-27T18:21:56.783744Z","steps":["trace[465708547] 'range keys from in-memory index tree' (duration: 124.343963ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:57.033369Z","caller":"traceutil/trace.go:171","msg":"trace[36908150] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"246.09659ms","start":"2026-02-27T18:21:56.787254Z","end":"2026-02-27T18:21:57.033351Z","steps":["trace[36908150] 'process raft request' (duration: 245.982078ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:57.033433Z","caller":"traceutil/trace.go:171","msg":"trace[1477076043] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:766; }","duration":"150.797924ms","start":"2026-02-27T18:21:56.882591Z","end":"2026-02-27T18:21:57.033389Z","steps":["trace[1477076043] 'read index received' (duration: 150.773163ms)","trace[1477076043] 'applied index is now lower than readState.Index' (duration: 20.711µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:21:57.033753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.107931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T18:21:57.033818Z","caller":"traceutil/trace.go:171","msg":"trace[469910826] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:742; }","duration":"151.258774ms","start":"2026-02-27T18:21:56.882542Z","end":"2026-02-27T18:21:57.033801Z","steps":["trace[469910826] 'agreement among raft nodes before linearized reading' (duration: 151.001468ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:21:57.365697Z","caller":"traceutil/trace.go:171","msg":"trace[159349061] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"429.822485ms","start":"2026-02-27T18:21:56.935849Z","end":"2026-02-27T18:21:57.365672Z","steps":["trace[159349061] 'process raft request' (duration: 429.692003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:21:57.365866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:21:56.935827Z","time spent":"429.957598ms","remote":"127.0.0.1:41982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5170,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:22:17.551424Z","caller":"traceutil/trace.go:171","msg":"trace[992988601] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"390.988757ms","start":"2026-02-27T18:22:17.160415Z","end":"2026-02-27T18:22:17.551403Z","steps":["trace[992988601] 'read index received' (duration: 390.774072ms)","trace[992988601] 'applied index is now lower than readState.Index' (duration: 213.495µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:22:17.551476Z","caller":"traceutil/trace.go:171","msg":"trace[452601065] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"577.831289ms","start":"2026-02-27T18:22:16.973612Z","end":"2026-02-27T18:22:17.551444Z","steps":["trace[452601065] 'process raft request' (duration: 577.618953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:17.551667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:16.973589Z","time spent":"577.962412ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:22:17.552234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.813995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T18:22:17.552642Z","caller":"traceutil/trace.go:171","msg":"trace[1096315981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"392.271155ms","start":"2026-02-27T18:22:17.16034Z","end":"2026-02-27T18:22:17.552611Z","steps":["trace[1096315981] 'agreement among raft nodes before linearized reading' (duration: 391.161101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:17.552689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:17.160306Z","time spent":"392.367777ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T18:22:17.955683Z","caller":"traceutil/trace.go:171","msg":"trace[1687360069] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"395.371271ms","start":"2026-02-27T18:22:17.560288Z","end":"2026-02-27T18:22:17.955659Z","steps":["trace[1687360069] 'process raft request' (duration: 395.216518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:17.955972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:17.560265Z","time spent":"395.491164ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:22:18.036792Z","caller":"traceutil/trace.go:171","msg":"trace[2080757240] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:832; }","duration":"443.773726ms","start":"2026-02-27T18:22:17.592992Z","end":"2026-02-27T18:22:18.036766Z","steps":["trace[2080757240] 'read index received' (duration: 362.576534ms)","trace[2080757240] 'applied index is now lower than readState.Index' (duration: 81.195822ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:22:18.036917Z","caller":"traceutil/trace.go:171","msg":"trace[295313273] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"476.471872ms","start":"2026-02-27T18:22:17.560415Z","end":"2026-02-27T18:22:18.036887Z","steps":["trace[295313273] 'process raft request' (duration: 418.209415ms)","trace[295313273] 'compare' (duration: 58.013572ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:22:18.03707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:17.560389Z","time spent":"476.600294ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:22:18.037083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.35292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:22:18.037105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.043554ms","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-02-27T18:22:18.03716Z","caller":"traceutil/trace.go:171","msg":"trace[295670556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"312.481593ms","start":"2026-02-27T18:22:17.72466Z","end":"2026-02-27T18:22:18.037142Z","steps":["trace[295670556] 'agreement among raft nodes before linearized reading' (duration: 312.32458ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:18.037165Z","caller":"traceutil/trace.go:171","msg":"trace[305474602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"401.158786ms","start":"2026-02-27T18:22:17.635991Z","end":"2026-02-27T18:22:18.03715Z","steps":["trace[305474602] 'agreement among raft nodes before linearized reading' (duration: 400.947152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:18.037195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:17.724639Z","time spent":"312.549745ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T18:22:18.03721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:17.635968Z","time spent":"401.230928ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T18:22:18.037551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.545413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T18:22:18.03761Z","caller":"traceutil/trace.go:171","msg":"trace[847944936] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"444.642335ms","start":"2026-02-27T18:22:17.592954Z","end":"2026-02-27T18:22:18.037597Z","steps":["trace[847944936] 'agreement among raft nodes before linearized reading' (duration: 443.94627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:18.037685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:17.592939Z","time spent":"444.736367ms","remote":"127.0.0.1:42062","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-02-27T18:22:18.157028Z","caller":"traceutil/trace.go:171","msg":"trace[2008860270] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"115.799639ms","start":"2026-02-27T18:22:18.041207Z","end":"2026-02-27T18:22:18.157007Z","steps":["trace[2008860270] 'process raft request' (duration: 103.932952ms)","trace[2008860270] 'compare' (duration: 11.736514ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:22:18.157274Z","caller":"traceutil/trace.go:171","msg":"trace[1284514141] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"114.333937ms","start":"2026-02-27T18:22:18.042915Z","end":"2026-02-27T18:22:18.157249Z","steps":["trace[1284514141] 'process raft request' (duration: 114.054071ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:20.317216Z","caller":"traceutil/trace.go:171","msg":"trace[18563630] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"275.934645ms","start":"2026-02-27T18:22:20.041258Z","end":"2026-02-27T18:22:20.317193Z","steps":["trace[18563630] 'read index received' (duration: 275.750771ms)","trace[18563630] 'applied index is now lower than readState.Index' (duration: 182.674µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:22:20.317268Z","caller":"traceutil/trace.go:171","msg":"trace[1536496138] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"352.821804ms","start":"2026-02-27T18:22:19.964415Z","end":"2026-02-27T18:22:20.317237Z","steps":["trace[1536496138] 'process raft request' (duration: 352.578688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:20.317401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.137349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T18:22:20.317456Z","caller":"traceutil/trace.go:171","msg":"trace[560610048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"276.234531ms","start":"2026-02-27T18:22:20.041209Z","end":"2026-02-27T18:22:20.317443Z","steps":["trace[560610048] 'agreement among raft nodes before linearized reading' (duration: 276.080288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:20.317459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:19.964398Z","time spent":"352.942586ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:22:20.317537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.233691ms","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-02-27T18:22:20.317604Z","caller":"traceutil/trace.go:171","msg":"trace[43105132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"156.331703ms","start":"2026-02-27T18:22:20.161258Z","end":"2026-02-27T18:22:20.317589Z","steps":["trace[43105132] 'agreement among raft nodes before linearized reading' (duration: 156.153539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:20.317647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.63129ms","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-02-27T18:22:20.317717Z","caller":"traceutil/trace.go:171","msg":"trace[290202655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"156.734852ms","start":"2026-02-27T18:22:20.160963Z","end":"2026-02-27T18:22:20.317698Z","steps":["trace[290202655] 'agreement among raft nodes before linearized reading' (duration: 156.484707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:20.317788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.207436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T18:22:20.31782Z","caller":"traceutil/trace.go:171","msg":"trace[2119274063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"255.273748ms","start":"2026-02-27T18:22:20.062535Z","end":"2026-02-27T18:22:20.317809Z","steps":["trace[2119274063] 'agreement among raft nodes before linearized reading' (duration: 254.849669ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:20.771043Z","caller":"traceutil/trace.go:171","msg":"trace[282080274] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"447.855084ms","start":"2026-02-27T18:22:20.321243Z","end":"2026-02-27T18:22:20.769098Z","steps":["trace[282080274] 'process raft request' (duration: 447.66541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:20.771218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:20.321223Z","time spent":"449.901849ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:22:20.974221Z","caller":"traceutil/trace.go:171","msg":"trace[267167360] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"652.742925ms","start":"2026-02-27T18:22:20.321433Z","end":"2026-02-27T18:22:20.974176Z","steps":["trace[267167360] 'process raft request' (duration: 583.846589ms)","trace[267167360] 'compare' (duration: 68.519518ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:22:20.974426Z","caller":"traceutil/trace.go:171","msg":"trace[2140388015] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"652.96592ms","start":"2026-02-27T18:22:20.321433Z","end":"2026-02-27T18:22:20.974399Z","steps":["trace[2140388015] 'process raft request' (duration: 652.736875ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:20.974542Z","caller":"traceutil/trace.go:171","msg":"trace[220233283] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"652.722125ms","start":"2026-02-27T18:22:20.321809Z","end":"2026-02-27T18:22:20.974531Z","steps":["trace[220233283] 'process raft request' (duration: 652.464359ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:20.974391Z","caller":"traceutil/trace.go:171","msg":"trace[2094342823] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:839; }","duration":"248.822529ms","start":"2026-02-27T18:22:20.725538Z","end":"2026-02-27T18:22:20.97436Z","steps":["trace[2094342823] 'read index received' (duration: 43.333605ms)","trace[2094342823] 'applied index is now lower than readState.Index' (duration: 205.487484ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:22:20.974496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.941901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:22:20.974626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:20.321413Z","time spent":"653.119634ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:22:20.97465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:20.321803Z","time spent":"652.784106ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:22:20.974682Z","caller":"traceutil/trace.go:171","msg":"trace[681045835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"249.213798ms","start":"2026-02-27T18:22:20.725447Z","end":"2026-02-27T18:22:20.97466Z","steps":["trace[681045835] 'agreement among raft nodes before linearized reading' (duration: 248.996573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:20.974789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:20.321424Z","time spent":"653.135754ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:22:35.30469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.15159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-27T18:22:35.304788Z","caller":"traceutil/trace.go:171","msg":"trace[1915202233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"207.295792ms","start":"2026-02-27T18:22:35.097473Z","end":"2026-02-27T18:22:35.304769Z","steps":["trace[1915202233] 'range keys from in-memory index tree' (duration: 207.039837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:35.30469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.055289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T18:22:35.304912Z","caller":"traceutil/trace.go:171","msg":"trace[1637975301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:906; }","duration":"195.305834ms","start":"2026-02-27T18:22:35.109585Z","end":"2026-02-27T18:22:35.304891Z","steps":["trace[1637975301] 'range keys from in-memory index tree' (duration: 194.884875ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:37.111524Z","caller":"traceutil/trace.go:171","msg":"trace[349226318] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"211.472784ms","start":"2026-02-27T18:22:36.90003Z","end":"2026-02-27T18:22:37.111503Z","steps":["trace[349226318] 'process raft request' (duration: 211.068766ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:38.627726Z","caller":"traceutil/trace.go:171","msg":"trace[1025727347] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"784.200432ms","start":"2026-02-27T18:22:37.843509Z","end":"2026-02-27T18:22:38.62771Z","steps":["trace[1025727347] 'process raft request' (duration: 784.08684ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:38.627781Z","caller":"traceutil/trace.go:171","msg":"trace[1137497802] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:954; }","duration":"290.482989ms","start":"2026-02-27T18:22:38.337255Z","end":"2026-02-27T18:22:38.627737Z","steps":["trace[1137497802] 'read index received' (duration: 290.470929ms)","trace[1137497802] 'applied index is now lower than readState.Index' (duration: 9.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:22:38.628089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.834316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T18:22:38.628116Z","caller":"traceutil/trace.go:171","msg":"trace[57052207] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:919; }","duration":"290.950899ms","start":"2026-02-27T18:22:38.337158Z","end":"2026-02-27T18:22:38.628109Z","steps":["trace[57052207] 'agreement among raft nodes before linearized reading' (duration: 290.905788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:38.628169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.794732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T18:22:38.628217Z","caller":"traceutil/trace.go:171","msg":"trace[946581576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"225.908435ms","start":"2026-02-27T18:22:38.402293Z","end":"2026-02-27T18:22:38.628201Z","steps":["trace[946581576] 'agreement among raft nodes before linearized reading' (duration: 225.6665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:38.62784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:37.843462Z","time spent":"784.322795ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:22:39.36554Z","caller":"traceutil/trace.go:171","msg":"trace[1656911199] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"731.510885ms","start":"2026-02-27T18:22:38.634006Z","end":"2026-02-27T18:22:39.365517Z","steps":["trace[1656911199] 'process raft request' (duration: 677.885637ms)","trace[1656911199] 'compare' (duration: 53.413823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:22:39.365662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:38.633968Z","time spent":"731.620457ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:22:39.751611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.328863ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-27T18:22:39.751741Z","caller":"traceutil/trace.go:171","msg":"trace[867006199] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:954; }","duration":"772.691183ms","start":"2026-02-27T18:22:38.979002Z","end":"2026-02-27T18:22:39.751693Z","steps":["trace[867006199] 'read index received' (duration: 332.899812ms)","trace[867006199] 'applied index is now lower than readState.Index' (duration: 439.789961ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:22:39.751908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.866347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T18:22:39.751938Z","caller":"traceutil/trace.go:171","msg":"trace[136140496] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:920; }","duration":"772.934449ms","start":"2026-02-27T18:22:38.978993Z","end":"2026-02-27T18:22:39.751927Z","steps":["trace[136140496] 'agreement among raft nodes before linearized reading' (duration: 772.826596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:39.751972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:38.978903Z","time spent":"773.060191ms","remote":"127.0.0.1:41908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-27T18:22:39.752091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.599882ms","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-02-27T18:22:39.752159Z","caller":"traceutil/trace.go:171","msg":"trace[921450617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"430.691003ms","start":"2026-02-27T18:22:39.32145Z","end":"2026-02-27T18:22:39.752141Z","steps":["trace[921450617] 'agreement among raft nodes before linearized reading' (duration: 430.518399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:39.752184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.380865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-27T18:22:39.752197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:39.321442Z","time spent":"430.746605ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T18:22:39.752223Z","caller":"traceutil/trace.go:171","msg":"trace[1251899921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"625.440427ms","start":"2026-02-27T18:22:39.126768Z","end":"2026-02-27T18:22:39.752209Z","steps":["trace[1251899921] 'agreement among raft nodes before linearized reading' (duration: 625.361624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:39.752207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.370008ms","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-02-27T18:22:39.752268Z","caller":"traceutil/trace.go:171","msg":"trace[1996834208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:920; }","duration":"431.480541ms","start":"2026-02-27T18:22:39.320775Z","end":"2026-02-27T18:22:39.752255Z","steps":["trace[1996834208] 'agreement among raft nodes before linearized reading' (duration: 431.277375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:39.752267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:39.126751Z","time spent":"625.508929ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T18:22:39.752296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:39.320756Z","time spent":"431.535593ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T18:22:39.752091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.039874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T18:22:39.752358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.606621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T18:22:39.752367Z","caller":"traceutil/trace.go:171","msg":"trace[232959084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:920; }","duration":"629.366072ms","start":"2026-02-27T18:22:39.122987Z","end":"2026-02-27T18:22:39.752353Z","steps":["trace[232959084] 'agreement among raft nodes before linearized reading' (duration: 628.961312ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:39.752393Z","caller":"traceutil/trace.go:171","msg":"trace[1445778340] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:920; }","duration":"245.679882ms","start":"2026-02-27T18:22:39.506703Z","end":"2026-02-27T18:22:39.752383Z","steps":["trace[1445778340] 'agreement among raft nodes before linearized reading' (duration: 245.622641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:39.752407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:39.122958Z","time spent":"629.438793ms","remote":"127.0.0.1:42062","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-02-27T18:22:52.075829Z","caller":"traceutil/trace.go:171","msg":"trace[606583763] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"101.141253ms","start":"2026-02-27T18:22:51.974661Z","end":"2026-02-27T18:22:52.075803Z","steps":["trace[606583763] 'process raft request' (duration: 25.515901ms)","trace[606583763] 'compare' (duration: 75.492259ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:22:53.913278Z","caller":"traceutil/trace.go:171","msg":"trace[244202055] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"349.693896ms","start":"2026-02-27T18:22:53.563567Z","end":"2026-02-27T18:22:53.913261Z","steps":["trace[244202055] 'read index received' (duration: 349.572974ms)","trace[244202055] 'applied index is now lower than readState.Index' (duration: 120.582µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:22:53.913437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.284127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-bm5rf\" ","response":"range_response_count:1 size:10490"} {"level":"warn","ts":"2026-02-27T18:22:53.913428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.85255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T18:22:53.913458Z","caller":"traceutil/trace.go:171","msg":"trace[1340009760] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-bm5rf; range_end:; response_count:1; response_revision:964; }","duration":"323.359898ms","start":"2026-02-27T18:22:53.590092Z","end":"2026-02-27T18:22:53.913452Z","steps":["trace[1340009760] 'agreement among raft nodes before linearized reading' (duration: 323.256796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:53.913479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:53.590076Z","time spent":"323.398269ms","remote":"127.0.0.1:41996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":10512,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-bm5rf\" "} {"level":"info","ts":"2026-02-27T18:22:53.913479Z","caller":"traceutil/trace.go:171","msg":"trace[245233172] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:964; }","duration":"349.947132ms","start":"2026-02-27T18:22:53.563518Z","end":"2026-02-27T18:22:53.913465Z","steps":["trace[245233172] 'agreement among raft nodes before linearized reading' (duration: 349.813519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:53.913482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.762353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:22:53.913511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:53.5635Z","time spent":"350.003753ms","remote":"127.0.0.1:41844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-02-27T18:22:53.91348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.993648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:22:53.913537Z","caller":"traceutil/trace.go:171","msg":"trace[1489947428] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:964; }","duration":"245.870586ms","start":"2026-02-27T18:22:53.667656Z","end":"2026-02-27T18:22:53.913526Z","steps":["trace[1489947428] 'agreement among raft nodes before linearized reading' (duration: 245.749923ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:53.91358Z","caller":"traceutil/trace.go:171","msg":"trace[1659142980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"189.148482ms","start":"2026-02-27T18:22:53.72441Z","end":"2026-02-27T18:22:53.913559Z","steps":["trace[1659142980] 'agreement among raft nodes before linearized reading' (duration: 188.965028ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:22:53.913344Z","caller":"traceutil/trace.go:171","msg":"trace[1614690051] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"463.050802ms","start":"2026-02-27T18:22:53.450262Z","end":"2026-02-27T18:22:53.913312Z","steps":["trace[1614690051] 'process raft request' (duration: 462.895149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:53.91415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:53.450243Z","time spent":"463.8473ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:22:56.052936Z","caller":"traceutil/trace.go:171","msg":"trace[227605349] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"133.327296ms","start":"2026-02-27T18:22:55.919406Z","end":"2026-02-27T18:22:56.052733Z","steps":["trace[227605349] 'read index received' (duration: 133.274555ms)","trace[227605349] 'applied index is now lower than readState.Index' (duration: 51.981µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:22:56.053194Z","caller":"traceutil/trace.go:171","msg":"trace[2011789575] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"135.037363ms","start":"2026-02-27T18:22:55.918151Z","end":"2026-02-27T18:22:56.053188Z","steps":["trace[2011789575] 'process raft request' (duration: 134.487762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:56.053456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.032842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T18:22:56.053537Z","caller":"traceutil/trace.go:171","msg":"trace[1719657301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"134.164724ms","start":"2026-02-27T18:22:55.919354Z","end":"2026-02-27T18:22:56.053519Z","steps":["trace[1719657301] 'agreement among raft nodes before linearized reading' (duration: 133.928118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:58.570667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742996989059964,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T18:22:58.88563Z","caller":"traceutil/trace.go:171","msg":"trace[1931174125] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"817.687405ms","start":"2026-02-27T18:22:58.067921Z","end":"2026-02-27T18:22:58.885608Z","steps":["trace[1931174125] 'process raft request' (duration: 817.551961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:22:58.885763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.067901Z","time spent":"817.793798ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:00.004652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.625215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:00.004813Z","caller":"traceutil/trace.go:171","msg":"trace[1995720184] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.935364202s","start":"2026-02-27T18:22:58.069421Z","end":"2026-02-27T18:23:00.004785Z","steps":["trace[1995720184] 'process raft request' (duration: 1.712532663s)","trace[1995720184] 'compare' (duration: 222.292458ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:00.004748Z","caller":"traceutil/trace.go:171","msg":"trace[1447520539] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1028; }","duration":"1.934569736s","start":"2026-02-27T18:22:58.070161Z","end":"2026-02-27T18:23:00.004731Z","steps":["trace[1447520539] 'read index received' (duration: 815.339524ms)","trace[1447520539] 'applied index is now lower than readState.Index' (duration: 1.119228892s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:00.005323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.069395Z","time spent":"1.93571986s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:00.005347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.935179989s","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-02-27T18:23:00.005416Z","caller":"traceutil/trace.go:171","msg":"trace[1116661509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"1.93525541s","start":"2026-02-27T18:22:58.070123Z","end":"2026-02-27T18:23:00.005379Z","steps":["trace[1116661509] 'agreement among raft nodes before linearized reading' (duration: 1.935039675s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.005548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.070101Z","time spent":"1.935413274s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T18:23:00.382052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.035499ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-27T18:23:00.382502Z","caller":"traceutil/trace.go:171","msg":"trace[1467467627] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.4352511s","start":"2026-02-27T18:22:58.94721Z","end":"2026-02-27T18:23:00.382461Z","steps":["trace[1467467627] 'process raft request' (duration: 1.435000315s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:00.382605Z","caller":"traceutil/trace.go:171","msg":"trace[1670581811] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.434041594s","start":"2026-02-27T18:22:58.948531Z","end":"2026-02-27T18:23:00.382573Z","steps":["trace[1670581811] 'process raft request' (duration: 1.433791349s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:00.382717Z","caller":"traceutil/trace.go:171","msg":"trace[393268255] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1030; }","duration":"377.566357ms","start":"2026-02-27T18:23:00.005135Z","end":"2026-02-27T18:23:00.382702Z","steps":["trace[393268255] 'read index received' (duration: 112.799823ms)","trace[393268255] 'applied index is now lower than readState.Index' (duration: 264.764634ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:00.383337Z","caller":"traceutil/trace.go:171","msg":"trace[1912780484] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"354.96706ms","start":"2026-02-27T18:23:00.02832Z","end":"2026-02-27T18:23:00.383287Z","steps":["trace[1912780484] 'process raft request' (duration: 354.307476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.384067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450511589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T18:23:00.384167Z","caller":"traceutil/trace.go:171","msg":"trace[51778231] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:995; }","duration":"1.450644921s","start":"2026-02-27T18:22:58.933491Z","end":"2026-02-27T18:23:00.384136Z","steps":["trace[51778231] 'agreement among raft nodes before linearized reading' (duration: 1.449224961s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.384208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:00.02829Z","time spent":"355.823188ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:00.383998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.753751733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:00.384393Z","caller":"traceutil/trace.go:171","msg":"trace[2016587679] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:994; }","duration":"1.754185334s","start":"2026-02-27T18:22:58.630196Z","end":"2026-02-27T18:23:00.384382Z","steps":["trace[2016587679] 'agreement among raft nodes before linearized reading' (duration: 1.752552138s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.384421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.630176Z","time spent":"1.754236224s","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-27T18:23:00.384076Z","caller":"traceutil/trace.go:171","msg":"trace[2051285744] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.317012509s","start":"2026-02-27T18:22:59.065573Z","end":"2026-02-27T18:23:00.382586Z","steps":["trace[2051285744] 'process raft request' (duration: 1.316795864s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.384766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:59.065549Z","time spent":"1.319196196s","remote":"127.0.0.1:41982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6170,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:00.385146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68546608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:00.385173Z","caller":"traceutil/trace.go:171","msg":"trace[162423359] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:995; }","duration":"1.685529631s","start":"2026-02-27T18:22:58.699636Z","end":"2026-02-27T18:23:00.385166Z","steps":["trace[162423359] 'agreement among raft nodes before linearized reading' (duration: 1.68547775s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.385196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.699621Z","time spent":"1.685568832s","remote":"127.0.0.1:41956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-27T18:23:00.385198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.13447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T18:23:00.385286Z","caller":"traceutil/trace.go:171","msg":"trace[1691657295] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:995; }","duration":"752.279143ms","start":"2026-02-27T18:22:59.632985Z","end":"2026-02-27T18:23:00.385264Z","steps":["trace[1691657295] 'agreement among raft nodes before linearized reading' (duration: 752.12811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.385362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:59.632961Z","time spent":"752.383745ms","remote":"127.0.0.1:41982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-27T18:23:00.38549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.006216981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T18:23:00.385519Z","caller":"traceutil/trace.go:171","msg":"trace[447152438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"2.006268893s","start":"2026-02-27T18:22:58.379244Z","end":"2026-02-27T18:23:00.385513Z","steps":["trace[447152438] 'agreement among raft nodes before linearized reading' (duration: 2.006184431s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.385541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.379229Z","time spent":"2.006304213s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T18:23:00.385627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.933475Z","time spent":"1.450735284s","remote":"127.0.0.1:42290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-27T18:23:00.386009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.948519Z","time spent":"1.434156136s","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:23:00.386345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.034028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:00.386375Z","caller":"traceutil/trace.go:171","msg":"trace[651894450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"664.09331ms","start":"2026-02-27T18:22:59.722269Z","end":"2026-02-27T18:23:00.386362Z","steps":["trace[651894450] 'agreement among raft nodes before linearized reading' (duration: 660.476682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.386389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:59.722248Z","time spent":"664.13742ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T18:23:00.386611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.947193Z","time spent":"1.435382273s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:00.388407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.290124358s","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-02-27T18:23:00.38844Z","caller":"traceutil/trace.go:171","msg":"trace[1378426602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"2.29019693s","start":"2026-02-27T18:22:58.098235Z","end":"2026-02-27T18:23:00.388432Z","steps":["trace[1378426602] 'agreement among raft nodes before linearized reading' (duration: 2.289540696s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.388506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:22:58.098217Z","time spent":"2.290284871s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T18:23:00.811167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.926589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:00.811389Z","caller":"traceutil/trace.go:171","msg":"trace[66948227] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"418.49747ms","start":"2026-02-27T18:23:00.392868Z","end":"2026-02-27T18:23:00.811366Z","steps":["trace[66948227] 'process raft request' (duration: 196.148302ms)","trace[66948227] 'compare' (duration: 209.210544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:00.811561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:00.392854Z","time spent":"418.607073ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:23:00.811708Z","caller":"traceutil/trace.go:171","msg":"trace[319349947] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"418.104002ms","start":"2026-02-27T18:23:00.393594Z","end":"2026-02-27T18:23:00.811698Z","steps":["trace[319349947] 'process raft request' (duration: 417.655602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:00.811788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:00.393588Z","time spent":"418.171103ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:01.731122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742996989059978,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T18:23:01.742563Z","caller":"traceutil/trace.go:171","msg":"trace[4722828] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"511.949087ms","start":"2026-02-27T18:23:01.230597Z","end":"2026-02-27T18:23:01.742546Z","steps":["trace[4722828] 'read index received' (duration: 511.818174ms)","trace[4722828] 'applied index is now lower than readState.Index' (duration: 130.183µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:01.742711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.09666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:01.742737Z","caller":"traceutil/trace.go:171","msg":"trace[305705648] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1000; }","duration":"512.16484ms","start":"2026-02-27T18:23:01.230565Z","end":"2026-02-27T18:23:01.74273Z","steps":["trace[305705648] 'agreement among raft nodes before linearized reading' (duration: 512.078789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:01.742757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:01.230549Z","time spent":"512.202722ms","remote":"127.0.0.1:42222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-02-27T18:23:01.742723Z","caller":"traceutil/trace.go:171","msg":"trace[201250972] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"516.553046ms","start":"2026-02-27T18:23:01.226049Z","end":"2026-02-27T18:23:01.742602Z","steps":["trace[201250972] 'process raft request' (duration: 516.406373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:01.742974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:01.226027Z","time spent":"516.890473ms","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:23:02.470644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.361162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:02.47077Z","caller":"traceutil/trace.go:171","msg":"trace[1005486429] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"318.961053ms","start":"2026-02-27T18:23:02.151789Z","end":"2026-02-27T18:23:02.47075Z","steps":["trace[1005486429] 'read index received' (duration: 52.161µs)","trace[1005486429] 'applied index is now lower than readState.Index' (duration: 318.906432ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:02.470826Z","caller":"traceutil/trace.go:171","msg":"trace[1627176730] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"724.714218ms","start":"2026-02-27T18:23:01.746063Z","end":"2026-02-27T18:23:02.470777Z","steps":["trace[1627176730] 'process raft request' (duration: 170.10822ms)","trace[1627176730] 'compare' (duration: 554.149338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:02.471157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.353861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:23:02.471217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:01.746052Z","time spent":"725.043014ms","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":851,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:23:02.47123Z","caller":"traceutil/trace.go:171","msg":"trace[1497352523] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1001; }","duration":"319.472844ms","start":"2026-02-27T18:23:02.151746Z","end":"2026-02-27T18:23:02.471219Z","steps":["trace[1497352523] 'agreement among raft nodes before linearized reading' (duration: 319.322831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:02.471517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:02.151722Z","time spent":"319.763131ms","remote":"127.0.0.1:41938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-27T18:23:02.471827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.651408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T18:23:02.471944Z","caller":"traceutil/trace.go:171","msg":"trace[1846088553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"319.76919ms","start":"2026-02-27T18:23:02.15215Z","end":"2026-02-27T18:23:02.471919Z","steps":["trace[1846088553] 'agreement among raft nodes before linearized reading' (duration: 319.511374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:02.472016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:02.152146Z","time spent":"319.856452ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T18:23:02.47178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.433789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T18:23:02.472643Z","caller":"traceutil/trace.go:171","msg":"trace[1216699227] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1001; }","duration":"197.207125ms","start":"2026-02-27T18:23:02.275295Z","end":"2026-02-27T18:23:02.472502Z","steps":["trace[1216699227] 'agreement among raft nodes before linearized reading' (duration: 196.415959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:03.292142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.585902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:03.292315Z","caller":"traceutil/trace.go:171","msg":"trace[834582713] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1041; }","duration":"481.921118ms","start":"2026-02-27T18:23:02.81038Z","end":"2026-02-27T18:23:03.292301Z","steps":["trace[834582713] 'read index received' (duration: 202.108561ms)","trace[834582713] 'applied index is now lower than readState.Index' (duration: 279.811797ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:03.292411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.034212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:03.292394Z","caller":"traceutil/trace.go:171","msg":"trace[1978150249] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"814.848933ms","start":"2026-02-27T18:23:02.477519Z","end":"2026-02-27T18:23:03.292368Z","steps":["trace[1978150249] 'process raft request' (duration: 814.71498ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:03.292444Z","caller":"traceutil/trace.go:171","msg":"trace[308175781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"482.098172ms","start":"2026-02-27T18:23:02.810337Z","end":"2026-02-27T18:23:03.292436Z","steps":["trace[308175781] 'agreement among raft nodes before linearized reading' (duration: 482.032321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:03.292474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:02.810312Z","time spent":"482.157134ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T18:23:03.292517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:02.477509Z","time spent":"814.945795ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:23:03.292556Z","caller":"traceutil/trace.go:171","msg":"trace[1394071838] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"816.680272ms","start":"2026-02-27T18:23:02.475857Z","end":"2026-02-27T18:23:03.292537Z","steps":["trace[1394071838] 'process raft request' (duration: 536.610359ms)","trace[1394071838] 'compare' (duration: 279.294176ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:03.292651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:02.475837Z","time spent":"816.765015ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:03.292773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.259094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:03.292797Z","caller":"traceutil/trace.go:171","msg":"trace[1717358273] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1003; }","duration":"455.283635ms","start":"2026-02-27T18:23:02.837504Z","end":"2026-02-27T18:23:03.292788Z","steps":["trace[1717358273] 'agreement among raft nodes before linearized reading' (duration: 455.247074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:03.292816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:02.837463Z","time spent":"455.347756ms","remote":"127.0.0.1:42034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-27T18:23:03.292855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.372203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-27T18:23:03.292896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.769435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T18:23:03.292917Z","caller":"traceutil/trace.go:171","msg":"trace[434004427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"478.476255ms","start":"2026-02-27T18:23:02.81442Z","end":"2026-02-27T18:23:03.292896Z","steps":["trace[434004427] 'agreement among raft nodes before linearized reading' (duration: 478.299591ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:03.292925Z","caller":"traceutil/trace.go:171","msg":"trace[1388629291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"316.841716ms","start":"2026-02-27T18:23:02.976076Z","end":"2026-02-27T18:23:03.292917Z","steps":["trace[1388629291] 'agreement among raft nodes before linearized reading' (duration: 316.728144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:03.293134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:02.97605Z","time spent":"317.077292ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T18:23:03.292948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:02.814403Z","time spent":"478.539566ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T18:23:03.293254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.405137ms","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-02-27T18:23:03.293275Z","caller":"traceutil/trace.go:171","msg":"trace[1678595175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"455.477419ms","start":"2026-02-27T18:23:02.837791Z","end":"2026-02-27T18:23:03.293268Z","steps":["trace[1678595175] 'agreement among raft nodes before linearized reading' (duration: 454.931467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:03.29329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:02.83776Z","time spent":"455.526719ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T18:23:03.729561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.17764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:03.729759Z","caller":"traceutil/trace.go:171","msg":"trace[1962372899] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"432.201107ms","start":"2026-02-27T18:23:03.297446Z","end":"2026-02-27T18:23:03.729647Z","steps":["trace[1962372899] 'process raft request' (duration: 282.860893ms)","trace[1962372899] 'compare' (duration: 149.070248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:03.729827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:03.297435Z","time spent":"432.360071ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:23:04.068579Z","caller":"traceutil/trace.go:171","msg":"trace[1791026587] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1043; }","duration":"504.406483ms","start":"2026-02-27T18:23:03.564148Z","end":"2026-02-27T18:23:04.068555Z","steps":["trace[1791026587] 'read index received' (duration: 16.145827ms)","trace[1791026587] 'applied index is now lower than readState.Index' (duration: 488.259326ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:04.068621Z","caller":"traceutil/trace.go:171","msg":"trace[83158333] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"769.835602ms","start":"2026-02-27T18:23:03.298741Z","end":"2026-02-27T18:23:04.068577Z","steps":["trace[83158333] 'process raft request' (duration: 769.706059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:04.068762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:03.298702Z","time spent":"769.980695ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:04.068786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.624609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T18:23:04.068822Z","caller":"traceutil/trace.go:171","msg":"trace[1173384729] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:1006; }","duration":"504.732351ms","start":"2026-02-27T18:23:03.564078Z","end":"2026-02-27T18:23:04.068811Z","steps":["trace[1173384729] 'agreement among raft nodes before linearized reading' (duration: 504.576708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:04.068861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:03.56405Z","time spent":"504.803342ms","remote":"127.0.0.1:41844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"info","ts":"2026-02-27T18:23:04.068901Z","caller":"traceutil/trace.go:171","msg":"trace[94623062] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"771.067408ms","start":"2026-02-27T18:23:03.297786Z","end":"2026-02-27T18:23:04.068854Z","steps":["trace[94623062] 'process raft request' (duration: 748.546102ms)","trace[94623062] 'compare' (duration: 21.833641ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:04.069156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:03.297771Z","time spent":"771.255942ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:04.40812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.033631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:04.408231Z","caller":"traceutil/trace.go:171","msg":"trace[1184668677] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"215.384087ms","start":"2026-02-27T18:23:04.192829Z","end":"2026-02-27T18:23:04.408213Z","steps":["trace[1184668677] 'read index received' (duration: 53.161µs)","trace[1184668677] 'applied index is now lower than readState.Index' (duration: 215.329406ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:04.408322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.49324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:04.408356Z","caller":"traceutil/trace.go:171","msg":"trace[1952008624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"215.569571ms","start":"2026-02-27T18:23:04.192776Z","end":"2026-02-27T18:23:04.408346Z","steps":["trace[1952008624] 'agreement among raft nodes before linearized reading' (duration: 215.485719ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:04.408322Z","caller":"traceutil/trace.go:171","msg":"trace[2075279537] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"260.955671ms","start":"2026-02-27T18:23:04.147316Z","end":"2026-02-27T18:23:04.408271Z","steps":["trace[2075279537] 'process raft request' (duration: 44.680024ms)","trace[2075279537] 'compare' (duration: 215.905049ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:04.598572Z","caller":"traceutil/trace.go:171","msg":"trace[1202489769] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"115.980503ms","start":"2026-02-27T18:23:04.482567Z","end":"2026-02-27T18:23:04.598547Z","steps":["trace[1202489769] 'process raft request' (duration: 101.793266ms)","trace[1202489769] 'compare' (duration: 14.037163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:04.837608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.071809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:04.837686Z","caller":"traceutil/trace.go:171","msg":"trace[1526109396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"113.189732ms","start":"2026-02-27T18:23:04.724478Z","end":"2026-02-27T18:23:04.837668Z","steps":["trace[1526109396] 'range keys from in-memory index tree' (duration: 112.926637ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:04.838286Z","caller":"traceutil/trace.go:171","msg":"trace[404067797] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"113.010019ms","start":"2026-02-27T18:23:04.725245Z","end":"2026-02-27T18:23:04.838255Z","steps":["trace[404067797] 'process raft request' (duration: 42.15968ms)","trace[404067797] 'compare' (duration: 70.099073ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:05.096072Z","caller":"traceutil/trace.go:171","msg":"trace[1111409973] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"248.011021ms","start":"2026-02-27T18:23:04.848036Z","end":"2026-02-27T18:23:05.096047Z","steps":["trace[1111409973] 'read index received' (duration: 208.703633ms)","trace[1111409973] 'applied index is now lower than readState.Index' (duration: 39.306508ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:05.0961Z","caller":"traceutil/trace.go:171","msg":"trace[1458057116] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"253.248574ms","start":"2026-02-27T18:23:04.842815Z","end":"2026-02-27T18:23:05.096063Z","steps":["trace[1458057116] 'process raft request' (duration: 214.010787ms)","trace[1458057116] 'compare' (duration: 39.088003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:05.096211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.153765ms","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:5"} {"level":"info","ts":"2026-02-27T18:23:05.096247Z","caller":"traceutil/trace.go:171","msg":"trace[1908112689] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1010; }","duration":"248.220326ms","start":"2026-02-27T18:23:04.848013Z","end":"2026-02-27T18:23:05.096233Z","steps":["trace[1908112689] 'agreement among raft nodes before linearized reading' (duration: 248.120763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:05.418445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.079201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:05.418561Z","caller":"traceutil/trace.go:171","msg":"trace[2000094716] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"155.518496ms","start":"2026-02-27T18:23:05.263019Z","end":"2026-02-27T18:23:05.418537Z","steps":["trace[2000094716] 'read index received' (duration: 17.223681ms)","trace[2000094716] 'applied index is now lower than readState.Index' (duration: 138.293855ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:05.418653Z","caller":"traceutil/trace.go:171","msg":"trace[2101369900] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"241.293878ms","start":"2026-02-27T18:23:05.177347Z","end":"2026-02-27T18:23:05.418641Z","steps":["trace[2101369900] 'process raft request' (duration: 102.948282ms)","trace[2101369900] 'compare' (duration: 137.979498ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:05.419062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.269513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:05.419125Z","caller":"traceutil/trace.go:171","msg":"trace[1926262715] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1013; }","duration":"112.336134ms","start":"2026-02-27T18:23:05.306771Z","end":"2026-02-27T18:23:05.419108Z","steps":["trace[1926262715] 'agreement among raft nodes before linearized reading' (duration: 112.265013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:05.419106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.058957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:05.419254Z","caller":"traceutil/trace.go:171","msg":"trace[102943996] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1013; }","duration":"156.213501ms","start":"2026-02-27T18:23:05.262986Z","end":"2026-02-27T18:23:05.4192Z","steps":["trace[102943996] 'agreement among raft nodes before linearized reading' (duration: 156.024687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:05.41928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.80445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T18:23:05.419454Z","caller":"traceutil/trace.go:171","msg":"trace[1495969331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"122.017503ms","start":"2026-02-27T18:23:05.29742Z","end":"2026-02-27T18:23:05.419437Z","steps":["trace[1495969331] 'agreement among raft nodes before linearized reading' (duration: 121.632425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:05.418863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.994893ms","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-02-27T18:23:05.419801Z","caller":"traceutil/trace.go:171","msg":"trace[1047445170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"122.976034ms","start":"2026-02-27T18:23:05.296816Z","end":"2026-02-27T18:23:05.419792Z","steps":["trace[1047445170] 'agreement among raft nodes before linearized reading' (duration: 121.938452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:05.932481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.152778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:05.932649Z","caller":"traceutil/trace.go:171","msg":"trace[211215841] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1054; }","duration":"495.959762ms","start":"2026-02-27T18:23:05.436678Z","end":"2026-02-27T18:23:05.932637Z","steps":["trace[211215841] 'read index received' (duration: 22.466665ms)","trace[211215841] 'applied index is now lower than readState.Index' (duration: 473.492427ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:05.932696Z","caller":"traceutil/trace.go:171","msg":"trace[328600538] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"507.279916ms","start":"2026-02-27T18:23:05.42541Z","end":"2026-02-27T18:23:05.93269Z","steps":["trace[328600538] 'process raft request' (duration: 507.142283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:05.932744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:05.425388Z","time spent":"507.323217ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:23:05.932779Z","caller":"traceutil/trace.go:171","msg":"trace[2036636545] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"507.362227ms","start":"2026-02-27T18:23:05.42539Z","end":"2026-02-27T18:23:05.932753Z","steps":["trace[2036636545] 'process raft request' (duration: 135.879282ms)","trace[2036636545] 'compare' (duration: 370.974104ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:05.932896Z","caller":"traceutil/trace.go:171","msg":"trace[1183050029] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"507.438429ms","start":"2026-02-27T18:23:05.425451Z","end":"2026-02-27T18:23:05.932889Z","steps":["trace[1183050029] 'process raft request' (duration: 507.163013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:05.932907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:05.425365Z","time spent":"507.47089ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:23:05.932931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:05.425447Z","time spent":"507.4683ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:23:05.932969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.278838ms","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-02-27T18:23:05.933032Z","caller":"traceutil/trace.go:171","msg":"trace[186426557] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1017; }","duration":"496.371101ms","start":"2026-02-27T18:23:05.436647Z","end":"2026-02-27T18:23:05.933018Z","steps":["trace[186426557] 'agreement among raft nodes before linearized reading' (duration: 496.275239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:05.933073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:05.436635Z","time spent":"496.429142ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-27T18:23:05.944459Z","caller":"traceutil/trace.go:171","msg":"trace[479127141] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"475.91365ms","start":"2026-02-27T18:23:05.468526Z","end":"2026-02-27T18:23:05.94444Z","steps":["trace[479127141] 'process raft request' (duration: 475.825148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:05.944562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:05.468507Z","time spent":"476.005562ms","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":807,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:23:06.232163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.746049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:06.232414Z","caller":"traceutil/trace.go:171","msg":"trace[1703828946] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"663.840865ms","start":"2026-02-27T18:23:05.568527Z","end":"2026-02-27T18:23:06.232367Z","steps":["trace[1703828946] 'process raft request' (duration: 534.828071ms)","trace[1703828946] 'compare' (duration: 128.595385ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:06.232581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:05.568409Z","time spent":"664.0928ms","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":808,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:23:06.232608Z","caller":"traceutil/trace.go:171","msg":"trace[35416015] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"120.810767ms","start":"2026-02-27T18:23:06.111778Z","end":"2026-02-27T18:23:06.232589Z","steps":["trace[35416015] 'process raft request' (duration: 120.697685ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.23266Z","caller":"traceutil/trace.go:171","msg":"trace[786270442] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"294.098307ms","start":"2026-02-27T18:23:05.938506Z","end":"2026-02-27T18:23:06.232604Z","steps":["trace[786270442] 'process raft request' (duration: 293.747669ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.232659Z","caller":"traceutil/trace.go:171","msg":"trace[1977205590] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"294.015964ms","start":"2026-02-27T18:23:05.938615Z","end":"2026-02-27T18:23:06.232631Z","steps":["trace[1977205590] 'process raft request' (duration: 293.81824ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.232934Z","caller":"traceutil/trace.go:171","msg":"trace[332454834] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1058; }","duration":"300.251309ms","start":"2026-02-27T18:23:05.932669Z","end":"2026-02-27T18:23:06.23292Z","steps":["trace[332454834] 'read index received' (duration: 11.640712ms)","trace[332454834] 'applied index is now lower than readState.Index' (duration: 288.609077ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:06.233224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.621024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T18:23:06.233365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.371742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-02-27T18:23:06.233392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.464689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:06.233424Z","caller":"traceutil/trace.go:171","msg":"trace[1848822339] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1022; }","duration":"769.459433ms","start":"2026-02-27T18:23:05.46395Z","end":"2026-02-27T18:23:06.233409Z","steps":["trace[1848822339] 'agreement among raft nodes before linearized reading' (duration: 769.34183ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.233437Z","caller":"traceutil/trace.go:171","msg":"trace[243290936] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1022; }","duration":"196.525061ms","start":"2026-02-27T18:23:06.036896Z","end":"2026-02-27T18:23:06.233421Z","steps":["trace[243290936] 'agreement among raft nodes before linearized reading' (duration: 196.46606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:06.233467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:05.463937Z","time spent":"769.521385ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-27T18:23:06.23323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.7734ms","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:534"} {"level":"info","ts":"2026-02-27T18:23:06.233577Z","caller":"traceutil/trace.go:171","msg":"trace[1449043343] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1022; }","duration":"295.15644ms","start":"2026-02-27T18:23:05.938409Z","end":"2026-02-27T18:23:06.233566Z","steps":["trace[1449043343] 'agreement among raft nodes before linearized reading' (duration: 294.628198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:06.233241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.096917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T18:23:06.23367Z","caller":"traceutil/trace.go:171","msg":"trace[448758216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1022; }","duration":"498.6433ms","start":"2026-02-27T18:23:05.735019Z","end":"2026-02-27T18:23:06.233662Z","steps":["trace[448758216] 'agreement among raft nodes before linearized reading' (duration: 497.997955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:06.233706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:05.734993Z","time spent":"498.705022ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T18:23:06.233335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.419943ms","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-02-27T18:23:06.233799Z","caller":"traceutil/trace.go:171","msg":"trace[258350044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1022; }","duration":"161.945915ms","start":"2026-02-27T18:23:06.071838Z","end":"2026-02-27T18:23:06.233784Z","steps":["trace[258350044] 'agreement among raft nodes before linearized reading' (duration: 161.423382ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.233448Z","caller":"traceutil/trace.go:171","msg":"trace[289850742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1022; }","duration":"159.834749ms","start":"2026-02-27T18:23:06.07356Z","end":"2026-02-27T18:23:06.233395Z","steps":["trace[289850742] 'agreement among raft nodes before linearized reading' (duration: 159.586484ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.539474Z","caller":"traceutil/trace.go:171","msg":"trace[73000617] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"296.710432ms","start":"2026-02-27T18:23:06.242741Z","end":"2026-02-27T18:23:06.539451Z","steps":["trace[73000617] 'process raft request' (duration: 296.669602ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.539995Z","caller":"traceutil/trace.go:171","msg":"trace[361743376] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"299.681747ms","start":"2026-02-27T18:23:06.240296Z","end":"2026-02-27T18:23:06.539978Z","steps":["trace[361743376] 'process raft request' (duration: 215.264045ms)","trace[361743376] 'compare' (duration: 83.40924ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:23:06.540334Z","caller":"traceutil/trace.go:171","msg":"trace[684325858] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"298.838409ms","start":"2026-02-27T18:23:06.241475Z","end":"2026-02-27T18:23:06.540313Z","steps":["trace[684325858] 'process raft request' (duration: 297.876298ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.540325Z","caller":"traceutil/trace.go:171","msg":"trace[116329928] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"299.591315ms","start":"2026-02-27T18:23:06.240702Z","end":"2026-02-27T18:23:06.540294Z","steps":["trace[116329928] 'process raft request' (duration: 298.423491ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.540641Z","caller":"traceutil/trace.go:171","msg":"trace[1448222239] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"299.079204ms","start":"2026-02-27T18:23:06.241547Z","end":"2026-02-27T18:23:06.540626Z","steps":["trace[1448222239] 'process raft request' (duration: 297.832778ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.540649Z","caller":"traceutil/trace.go:171","msg":"trace[27385102] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"299.323769ms","start":"2026-02-27T18:23:06.241309Z","end":"2026-02-27T18:23:06.540632Z","steps":["trace[27385102] 'process raft request' (duration: 297.874789ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.540837Z","caller":"traceutil/trace.go:171","msg":"trace[1893240738] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"299.478013ms","start":"2026-02-27T18:23:06.241345Z","end":"2026-02-27T18:23:06.540823Z","steps":["trace[1893240738] 'process raft request' (duration: 297.886959ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:06.541007Z","caller":"traceutil/trace.go:171","msg":"trace[240694750] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"299.584045ms","start":"2026-02-27T18:23:06.24141Z","end":"2026-02-27T18:23:06.540994Z","steps":["trace[240694750] 'process raft request' (duration: 297.90757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:07.247277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.898648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:23:07.247347Z","caller":"traceutil/trace.go:171","msg":"trace[1983466858] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"525.128731ms","start":"2026-02-27T18:23:06.722208Z","end":"2026-02-27T18:23:07.247337Z","steps":["trace[1983466858] 'read index received' (duration: 398.137631ms)","trace[1983466858] 'applied index is now lower than readState.Index' (duration: 126.99034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:07.247399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.190902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:23:07.247415Z","caller":"traceutil/trace.go:171","msg":"trace[1817591177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"525.208842ms","start":"2026-02-27T18:23:06.722201Z","end":"2026-02-27T18:23:07.24741Z","steps":["trace[1817591177] 'agreement among raft nodes before linearized reading' (duration: 525.160741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:07.247431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:06.722138Z","time spent":"525.289015ms","remote":"127.0.0.1:41824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T18:23:07.247585Z","caller":"traceutil/trace.go:171","msg":"trace[177625771] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"701.691401ms","start":"2026-02-27T18:23:06.545887Z","end":"2026-02-27T18:23:07.247578Z","steps":["trace[177625771] 'process raft request' (duration: 574.445075ms)","trace[177625771] 'compare' (duration: 126.796546ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:23:07.247614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:23:06.545881Z","time spent":"701.721041ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:23:08.736441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.520981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T18:23:08.73651Z","caller":"traceutil/trace.go:171","msg":"trace[1878182058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"190.629104ms","start":"2026-02-27T18:23:08.545865Z","end":"2026-02-27T18:23:08.736494Z","steps":["trace[1878182058] 'range keys from in-memory index tree' (duration: 190.263636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:23:08.736539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.819027ms","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-02-27T18:23:08.736569Z","caller":"traceutil/trace.go:171","msg":"trace[67363162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"189.867847ms","start":"2026-02-27T18:23:08.546692Z","end":"2026-02-27T18:23:08.73656Z","steps":["trace[67363162] 'range keys from in-memory index tree' (duration: 189.734985ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:08.990731Z","caller":"traceutil/trace.go:171","msg":"trace[1995131691] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"250.368273ms","start":"2026-02-27T18:23:08.740275Z","end":"2026-02-27T18:23:08.990643Z","steps":["trace[1995131691] 'process raft request' (duration: 250.21298ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:23:09.025267Z","caller":"traceutil/trace.go:171","msg":"trace[501024483] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"284.386906ms","start":"2026-02-27T18:23:08.740826Z","end":"2026-02-27T18:23:09.025213Z","steps":["trace[501024483] 'process raft request' (duration: 284.180211ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:26:07.35721Z","caller":"traceutil/trace.go:171","msg":"trace[190856679] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"183.832408ms","start":"2026-02-27T18:26:07.17335Z","end":"2026-02-27T18:26:07.357183Z","steps":["trace[190856679] 'process raft request' (duration: 183.698275ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:26:13.037811Z","caller":"traceutil/trace.go:171","msg":"trace[680124457] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"163.467826ms","start":"2026-02-27T18:26:12.874318Z","end":"2026-02-27T18:26:13.037786Z","steps":["trace[680124457] 'process raft request' (duration: 135.752485ms)","trace[680124457] 'compare' (duration: 27.413754ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:26:13.679067Z","caller":"traceutil/trace.go:171","msg":"trace[1677446453] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"105.239813ms","start":"2026-02-27T18:26:13.573795Z","end":"2026-02-27T18:26:13.679034Z","steps":["trace[1677446453] 'read index received' (duration: 105.045559ms)","trace[1677446453] 'applied index is now lower than readState.Index' (duration: 192.874µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:26:13.679308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.495508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T18:26:13.679362Z","caller":"traceutil/trace.go:171","msg":"trace[643681198] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:1925; }","duration":"105.610631ms","start":"2026-02-27T18:26:13.573735Z","end":"2026-02-27T18:26:13.679346Z","steps":["trace[643681198] 'agreement among raft nodes before linearized reading' (duration: 105.430877ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:26:13.679411Z","caller":"traceutil/trace.go:171","msg":"trace[1269683546] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"292.73653ms","start":"2026-02-27T18:26:13.386648Z","end":"2026-02-27T18:26:13.679385Z","steps":["trace[1269683546] 'process raft request' (duration: 292.182318ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:26:13.930244Z","caller":"traceutil/trace.go:171","msg":"trace[889530417] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"126.313369ms","start":"2026-02-27T18:26:13.803902Z","end":"2026-02-27T18:26:13.930216Z","steps":["trace[889530417] 'process raft request' (duration: 51.347113ms)","trace[889530417] 'compare' (duration: 74.854884ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:26:14.11632Z","caller":"traceutil/trace.go:171","msg":"trace[1878086086] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"160.783897ms","start":"2026-02-27T18:26:13.955515Z","end":"2026-02-27T18:26:14.116299Z","steps":["trace[1878086086] 'process raft request' (duration: 160.661035ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:26:14.11778Z","caller":"traceutil/trace.go:171","msg":"trace[1343193633] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"160.594842ms","start":"2026-02-27T18:26:13.957156Z","end":"2026-02-27T18:26:14.117751Z","steps":["trace[1343193633] 'process raft request' (duration: 160.453479ms)"],"step_count":1}