{"level":"warn","ts":"2026-02-26T20:13:06.935135Z","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-26T20:13:06.935311Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-26T20:13:06.935436Z","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-26T20:13:06.935457Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-02-26T20:13:06.935497Z","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-26T20:13:06.936593Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-02-26T20:13:06.93671Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-26T20:13:06.941718Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.706319ms"} {"level":"info","ts":"2026-02-26T20:13:06.948354Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-02-26T20:13:06.94844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T20:13:06.948467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-02-26T20:13:06.948482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T20:13:06.948497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-02-26T20:13:06.948544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-02-26T20:13:06.956119Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T20:13:06.957826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T20:13:06.95912Z","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-26T20:13:06.964794Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T20:13:06.966499Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T20:13:06.966757Z","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-26T20:13:06.966847Z","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-26T20:13:06.966859Z","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-26T20:13:06.968851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-02-26T20:13:06.970111Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-02-26T20:13:06.971602Z","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-26T20:13:06.971741Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-02-26T20:13:06.971766Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-02-26T20:13:06.971974Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T20:13:06.972032Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T20:13:07.149435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T20:13:07.149493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T20:13:07.149523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-02-26T20:13:07.149537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-02-26T20:13:07.149546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-02-26T20:13:07.149555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-02-26T20:13:07.149568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-02-26T20:13:07.154938Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:13:07.158176Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T20:13:07.158189Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T20:13:07.158329Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T20:13:07.15907Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T20:13:07.159155Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T20:13:07.160094Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:13:07.160164Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:13:07.160195Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:13:07.162401Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T20:13:07.166408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-02-26T20:13:36.604271Z","caller":"traceutil/trace.go:171","msg":"trace[325763054] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"189.945324ms","start":"2026-02-26T20:13:36.414302Z","end":"2026-02-26T20:13:36.604248Z","steps":["trace[325763054] 'process raft request' (duration: 189.267464ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:13:37.114342Z","caller":"traceutil/trace.go:171","msg":"trace[705994092] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"493.313989ms","start":"2026-02-26T20:13:36.621006Z","end":"2026-02-26T20:13:37.114319Z","steps":["trace[705994092] 'read index received' (duration: 431.442111ms)","trace[705994092] 'applied index is now lower than readState.Index' (duration: 61.870948ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:13:37.114484Z","caller":"traceutil/trace.go:171","msg":"trace[1439555774] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"505.955073ms","start":"2026-02-26T20:13:36.6085Z","end":"2026-02-26T20:13:37.114455Z","steps":["trace[1439555774] 'process raft request' (duration: 444.013563ms)","trace[1439555774] 'compare' (duration: 61.700453ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:13:37.11473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.623668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"warn","ts":"2026-02-26T20:13:37.114794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.786713ms","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-26T20:13:37.114853Z","caller":"traceutil/trace.go:171","msg":"trace[1523635192] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:466; }","duration":"493.830134ms","start":"2026-02-26T20:13:36.620999Z","end":"2026-02-26T20:13:37.114829Z","steps":["trace[1523635192] 'agreement among raft nodes before linearized reading' (duration: 493.513215ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:13:37.114864Z","caller":"traceutil/trace.go:171","msg":"trace[1025012444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"404.016181ms","start":"2026-02-26T20:13:36.710828Z","end":"2026-02-26T20:13:37.114844Z","steps":["trace[1025012444] 'agreement among raft nodes before linearized reading' (duration: 403.718222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:37.1149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:36.710812Z","time spent":"404.080762ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T20:13:37.114907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:36.620976Z","time spent":"493.921357ms","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-26T20:13:37.116092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:36.608482Z","time spent":"506.068417ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:13:39.620145Z","caller":"traceutil/trace.go:171","msg":"trace[1073552212] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"479.506732ms","start":"2026-02-26T20:13:39.140618Z","end":"2026-02-26T20:13:39.620124Z","steps":["trace[1073552212] 'read index received' (duration: 479.356557ms)","trace[1073552212] 'applied index is now lower than readState.Index' (duration: 149.395µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:13:39.620191Z","caller":"traceutil/trace.go:171","msg":"trace[940494702] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"491.248759ms","start":"2026-02-26T20:13:39.128921Z","end":"2026-02-26T20:13:39.62017Z","steps":["trace[940494702] 'process raft request' (duration: 491.078483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:39.620308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:39.12891Z","time spent":"491.327151ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:13:39.620336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.711849ms","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-26T20:13:39.620424Z","caller":"traceutil/trace.go:171","msg":"trace[136222807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"479.800101ms","start":"2026-02-26T20:13:39.140559Z","end":"2026-02-26T20:13:39.620359Z","steps":["trace[136222807] 'agreement among raft nodes before linearized reading' (duration: 479.673537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:39.620462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:39.14054Z","time spent":"479.912685ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T20:13:39.62057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.196325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T20:13:39.620555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.781011ms","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-26T20:13:39.620607Z","caller":"traceutil/trace.go:171","msg":"trace[1040709471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"338.260708ms","start":"2026-02-26T20:13:39.282326Z","end":"2026-02-26T20:13:39.620587Z","steps":["trace[1040709471] 'agreement among raft nodes before linearized reading' (duration: 338.221446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:39.620632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:39.282305Z","time spent":"338.321099ms","remote":"127.0.0.1:53586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T20:13:39.620624Z","caller":"traceutil/trace.go:171","msg":"trace[1643833746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"431.881574ms","start":"2026-02-26T20:13:39.188724Z","end":"2026-02-26T20:13:39.620606Z","steps":["trace[1643833746] 'agreement among raft nodes before linearized reading' (duration: 431.700469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:39.62067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:39.188712Z","time spent":"431.950846ms","remote":"127.0.0.1:53838","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-26T20:13:39.72851Z","caller":"traceutil/trace.go:171","msg":"trace[1359481239] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"104.085026ms","start":"2026-02-26T20:13:39.624399Z","end":"2026-02-26T20:13:39.728484Z","steps":["trace[1359481239] 'process raft request' (duration: 103.614302ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:13:39.729544Z","caller":"traceutil/trace.go:171","msg":"trace[320608428] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"104.804498ms","start":"2026-02-26T20:13:39.624709Z","end":"2026-02-26T20:13:39.729513Z","steps":["trace[320608428] 'process raft request' (duration: 104.673154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:44.03477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.749868ms","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-26T20:13:44.034853Z","caller":"traceutil/trace.go:171","msg":"trace[42941744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"276.889162ms","start":"2026-02-26T20:13:43.757945Z","end":"2026-02-26T20:13:44.034834Z","steps":["trace[42941744] 'range keys from in-memory index tree' (duration: 276.492721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:44.034843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.880884ms","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-26T20:13:44.034938Z","caller":"traceutil/trace.go:171","msg":"trace[1124755345] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"271.022519ms","start":"2026-02-26T20:13:43.763883Z","end":"2026-02-26T20:13:44.034905Z","steps":["trace[1124755345] 'range keys from in-memory index tree' (duration: 270.70978ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:13:44.422648Z","caller":"traceutil/trace.go:171","msg":"trace[939618271] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"383.124374ms","start":"2026-02-26T20:13:44.039501Z","end":"2026-02-26T20:13:44.422626Z","steps":["trace[939618271] 'process raft request' (duration: 383.00961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:44.422771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:44.039474Z","time spent":"383.230766ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:13:45.450252Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.027780255s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T20:13:46.216357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.648248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:13:46.216948Z","caller":"traceutil/trace.go:171","msg":"trace[1546660385] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"2.176797443s","start":"2026-02-26T20:13:44.040119Z","end":"2026-02-26T20:13:46.216917Z","steps":["trace[1546660385] 'process raft request' (duration: 1.410319981s)","trace[1546660385] 'compare' (duration: 765.492273ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:13:46.217039Z","caller":"traceutil/trace.go:171","msg":"trace[727602153] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:504; }","duration":"1.93574469s","start":"2026-02-26T20:13:44.281265Z","end":"2026-02-26T20:13:46.21701Z","steps":["trace[727602153] 'read index received' (duration: 141.234485ms)","trace[727602153] 'applied index is now lower than readState.Index' (duration: 1.794507565s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:13:46.217084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:44.040096Z","time spent":"2.176916757s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:13:46.236595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.955322618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:13:46.23665Z","caller":"traceutil/trace.go:171","msg":"trace[1011385748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"1.955416721s","start":"2026-02-26T20:13:44.281219Z","end":"2026-02-26T20:13:46.236636Z","steps":["trace[1011385748] 'agreement among raft nodes before linearized reading' (duration: 1.935914854s)","trace[1011385748] 'range keys from in-memory index tree' (duration: 19.396313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:13:46.236687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:44.281199Z","time spent":"1.955479743s","remote":"127.0.0.1:53586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:13:46.236769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593353429s","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-26T20:13:46.236826Z","caller":"traceutil/trace.go:171","msg":"trace[472142141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"1.593416511s","start":"2026-02-26T20:13:44.643396Z","end":"2026-02-26T20:13:46.236813Z","steps":["trace[472142141] 'agreement among raft nodes before linearized reading' (duration: 1.593240806s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:46.236864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:44.643339Z","time spent":"1.593517404s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T20:13:46.237063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.380778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T20:13:46.237155Z","caller":"traceutil/trace.go:171","msg":"trace[14250376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"590.483991ms","start":"2026-02-26T20:13:45.646648Z","end":"2026-02-26T20:13:46.237132Z","steps":["trace[14250376] 'agreement among raft nodes before linearized reading' (duration: 590.169631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:13:46.237443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:13:45.646594Z","time spent":"590.832832ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T20:14:04.63341Z","caller":"traceutil/trace.go:171","msg":"trace[1839674109] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"344.026348ms","start":"2026-02-26T20:14:04.289359Z","end":"2026-02-26T20:14:04.633386Z","steps":["trace[1839674109] 'read index received' (duration: 343.839794ms)","trace[1839674109] 'applied index is now lower than readState.Index' (duration: 185.274µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:14:04.633496Z","caller":"traceutil/trace.go:171","msg":"trace[756184735] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"537.583838ms","start":"2026-02-26T20:14:04.095893Z","end":"2026-02-26T20:14:04.633477Z","steps":["trace[756184735] 'process raft request' (duration: 537.347333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:04.633653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:04.095875Z","time spent":"537.65961ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:14:04.633694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.3717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T20:14:04.633759Z","caller":"traceutil/trace.go:171","msg":"trace[1105939560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:669; }","duration":"313.457492ms","start":"2026-02-26T20:14:04.320288Z","end":"2026-02-26T20:14:04.633745Z","steps":["trace[1105939560] 'agreement among raft nodes before linearized reading' (duration: 313.274967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:04.633792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:04.320281Z","time spent":"313.506064ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T20:14:04.634148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.784546ms","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-26T20:14:04.634202Z","caller":"traceutil/trace.go:171","msg":"trace[1431490635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:669; }","duration":"344.874388ms","start":"2026-02-26T20:14:04.289314Z","end":"2026-02-26T20:14:04.634189Z","steps":["trace[1431490635] 'agreement among raft nodes before linearized reading' (duration: 344.191962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:04.634243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:04.289296Z","time spent":"344.93632ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T20:14:04.634295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.629958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T20:14:04.634307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.815529ms","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-26T20:14:04.634342Z","caller":"traceutil/trace.go:171","msg":"trace[1150263117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:669; }","duration":"310.696779ms","start":"2026-02-26T20:14:04.323631Z","end":"2026-02-26T20:14:04.634327Z","steps":["trace[1150263117] 'agreement among raft nodes before linearized reading' (duration: 310.569016ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:04.634348Z","caller":"traceutil/trace.go:171","msg":"trace[995755562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:669; }","duration":"104.8832ms","start":"2026-02-26T20:14:04.529451Z","end":"2026-02-26T20:14:04.634335Z","steps":["trace[995755562] 'agreement among raft nodes before linearized reading' (duration: 104.773218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:04.634372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:04.32362Z","time spent":"310.74387ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T20:14:05.274888Z","caller":"traceutil/trace.go:171","msg":"trace[52371033] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"637.26792ms","start":"2026-02-26T20:14:04.637592Z","end":"2026-02-26T20:14:05.27486Z","steps":["trace[52371033] 'process raft request' (duration: 546.343068ms)","trace[52371033] 'compare' (duration: 90.810399ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:14:05.275034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:04.637571Z","time spent":"637.393092ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:14:05.693876Z","caller":"traceutil/trace.go:171","msg":"trace[1256074631] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"1.056041801s","start":"2026-02-26T20:14:04.637809Z","end":"2026-02-26T20:14:05.69385Z","steps":["trace[1256074631] 'process raft request' (duration: 1.055825366s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:05.694078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:04.637791Z","time spent":"1.056163664s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:14:05.694096Z","caller":"traceutil/trace.go:171","msg":"trace[65551596] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:695; }","duration":"412.987369ms","start":"2026-02-26T20:14:05.281089Z","end":"2026-02-26T20:14:05.694076Z","steps":["trace[65551596] 'read index received' (duration: 412.540009ms)","trace[65551596] 'applied index is now lower than readState.Index' (duration: 446.99µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:14:05.694121Z","caller":"traceutil/trace.go:171","msg":"trace[1887574360] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.054752821s","start":"2026-02-26T20:14:04.639357Z","end":"2026-02-26T20:14:05.69411Z","steps":["trace[1887574360] 'process raft request' (duration: 1.054633048s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:05.694174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.078331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:14:05.69415Z","caller":"traceutil/trace.go:171","msg":"trace[1939536297] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"1.05602022s","start":"2026-02-26T20:14:04.638102Z","end":"2026-02-26T20:14:05.694122Z","steps":["trace[1939536297] 'process raft request' (duration: 1.055708203s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:05.694187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:04.639349Z","time spent":"1.054801362s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:14:05.694198Z","caller":"traceutil/trace.go:171","msg":"trace[1612784438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"413.144713ms","start":"2026-02-26T20:14:05.281046Z","end":"2026-02-26T20:14:05.694191Z","steps":["trace[1612784438] 'agreement among raft nodes before linearized reading' (duration: 413.090592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:05.694224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:05.281024Z","time spent":"413.194454ms","remote":"127.0.0.1:53586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:14:05.694298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:04.63809Z","time spent":"1.056120783s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:14:05.855125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.058842ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T20:14:05.855204Z","caller":"traceutil/trace.go:171","msg":"trace[938342459] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"160.260062ms","start":"2026-02-26T20:14:05.694929Z","end":"2026-02-26T20:14:05.855189Z","steps":["trace[938342459] 'read index received' (duration: 34.162269ms)","trace[938342459] 'applied index is now lower than readState.Index' (duration: 126.096743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:14:05.85526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.321804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:14:05.85528Z","caller":"traceutil/trace.go:171","msg":"trace[356759051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"160.373415ms","start":"2026-02-26T20:14:05.694901Z","end":"2026-02-26T20:14:05.855274Z","steps":["trace[356759051] 'agreement among raft nodes before linearized reading' (duration: 160.323654ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:06.74681Z","caller":"traceutil/trace.go:171","msg":"trace[1049042623] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"105.166277ms","start":"2026-02-26T20:14:06.641595Z","end":"2026-02-26T20:14:06.746761Z","steps":["trace[1049042623] 'process raft request' (duration: 104.962552ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:29.628439Z","caller":"traceutil/trace.go:171","msg":"trace[67069078] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"346.891487ms","start":"2026-02-26T20:14:29.281519Z","end":"2026-02-26T20:14:29.62841Z","steps":["trace[67069078] 'read index received' (duration: 346.698663ms)","trace[67069078] 'applied index is now lower than readState.Index' (duration: 190.844µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:14:29.628594Z","caller":"traceutil/trace.go:171","msg":"trace[308463256] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"786.324218ms","start":"2026-02-26T20:14:28.842201Z","end":"2026-02-26T20:14:29.628525Z","steps":["trace[308463256] 'process raft request' (duration: 786.039402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:29.628886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:28.842185Z","time spent":"786.541462ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:14:29.628991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.033987ms","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-26T20:14:29.629129Z","caller":"traceutil/trace.go:171","msg":"trace[1107664659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"258.243931ms","start":"2026-02-26T20:14:29.370852Z","end":"2026-02-26T20:14:29.629096Z","steps":["trace[1107664659] 'agreement among raft nodes before linearized reading' (duration: 257.871822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:29.629572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.968961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:14:29.629736Z","caller":"traceutil/trace.go:171","msg":"trace[1732753697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"348.204277ms","start":"2026-02-26T20:14:29.281472Z","end":"2026-02-26T20:14:29.629676Z","steps":["trace[1732753697] 'agreement among raft nodes before linearized reading' (duration: 347.115252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:29.629851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:29.28145Z","time spent":"348.38439ms","remote":"127.0.0.1:53586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:14:30.292068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.027681ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:14:30.292223Z","caller":"traceutil/trace.go:171","msg":"trace[889889955] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:742; }","duration":"490.212485ms","start":"2026-02-26T20:14:29.80199Z","end":"2026-02-26T20:14:30.292202Z","steps":["trace[889889955] 'range keys from in-memory index tree' (duration: 490.00024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:30.292292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.765465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T20:14:30.292417Z","caller":"traceutil/trace.go:171","msg":"trace[1497263392] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:742; }","duration":"284.981449ms","start":"2026-02-26T20:14:30.007407Z","end":"2026-02-26T20:14:30.292389Z","steps":["trace[1497263392] 'count revisions from in-memory index tree' (duration: 284.644032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:30.292545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.596385ms","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-26T20:14:30.292569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.202976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T20:14:30.292631Z","caller":"traceutil/trace.go:171","msg":"trace[1733836578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"476.849991ms","start":"2026-02-26T20:14:29.81573Z","end":"2026-02-26T20:14:30.29258Z","steps":["trace[1733836578] 'range keys from in-memory index tree' (duration: 476.246198ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:30.292651Z","caller":"traceutil/trace.go:171","msg":"trace[1257413705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"476.325499ms","start":"2026-02-26T20:14:29.816303Z","end":"2026-02-26T20:14:30.292629Z","steps":["trace[1257413705] 'range keys from in-memory index tree' (duration: 476.002602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:30.292705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:29.815698Z","time spent":"476.965503ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T20:14:30.292708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:29.816293Z","time spent":"476.405001ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T20:14:30.292402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.727379ms","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-26T20:14:30.292884Z","caller":"traceutil/trace.go:171","msg":"trace[1599965969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"459.165449ms","start":"2026-02-26T20:14:29.83363Z","end":"2026-02-26T20:14:30.292795Z","steps":["trace[1599965969] 'range keys from in-memory index tree' (duration: 458.498464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:30.29294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:29.833561Z","time spent":"459.363744ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T20:14:30.466732Z","caller":"traceutil/trace.go:171","msg":"trace[242621637] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"168.48688ms","start":"2026-02-26T20:14:30.298213Z","end":"2026-02-26T20:14:30.4667Z","steps":["trace[242621637] 'process raft request' (duration: 168.334466ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:30.467152Z","caller":"traceutil/trace.go:171","msg":"trace[616473720] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"168.980861ms","start":"2026-02-26T20:14:30.297945Z","end":"2026-02-26T20:14:30.466926Z","steps":["trace[616473720] 'process raft request' (duration: 163.23474ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:31.576028Z","caller":"traceutil/trace.go:171","msg":"trace[416628107] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"259.708205ms","start":"2026-02-26T20:14:31.316285Z","end":"2026-02-26T20:14:31.575993Z","steps":["trace[416628107] 'process raft request' (duration: 259.48129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:31.75182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.254656ms","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-26T20:14:31.751918Z","caller":"traceutil/trace.go:171","msg":"trace[899939907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"117.396669ms","start":"2026-02-26T20:14:31.63449Z","end":"2026-02-26T20:14:31.751887Z","steps":["trace[899939907] 'range keys from in-memory index tree' (duration: 117.068601ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:31.859365Z","caller":"traceutil/trace.go:171","msg":"trace[1779178474] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"102.721065ms","start":"2026-02-26T20:14:31.756615Z","end":"2026-02-26T20:14:31.859336Z","steps":["trace[1779178474] 'process raft request' (duration: 102.538391ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:31.871002Z","caller":"traceutil/trace.go:171","msg":"trace[1372348518] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"114.184915ms","start":"2026-02-26T20:14:31.756803Z","end":"2026-02-26T20:14:31.870988Z","steps":["trace[1372348518] 'process raft request' (duration: 114.083863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:32.421614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.35843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:14:32.421695Z","caller":"traceutil/trace.go:171","msg":"trace[243297056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"140.508494ms","start":"2026-02-26T20:14:32.281168Z","end":"2026-02-26T20:14:32.421676Z","steps":["trace[243297056] 'range keys from in-memory index tree' (duration: 140.218568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:32.42187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.188744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T20:14:32.421942Z","caller":"traceutil/trace.go:171","msg":"trace[381468191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"106.320087ms","start":"2026-02-26T20:14:32.315604Z","end":"2026-02-26T20:14:32.421924Z","steps":["trace[381468191] 'range keys from in-memory index tree' (duration: 105.950849ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:32.688101Z","caller":"traceutil/trace.go:171","msg":"trace[459198045] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"206.816072ms","start":"2026-02-26T20:14:32.481255Z","end":"2026-02-26T20:14:32.688072Z","steps":["trace[459198045] 'process raft request' (duration: 206.686289ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:32.699947Z","caller":"traceutil/trace.go:171","msg":"trace[1756924076] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"218.297883ms","start":"2026-02-26T20:14:32.481625Z","end":"2026-02-26T20:14:32.699923Z","steps":["trace[1756924076] 'process raft request' (duration: 218.15478ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:41.837948Z","caller":"traceutil/trace.go:171","msg":"trace[1190444997] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"118.396572ms","start":"2026-02-26T20:14:41.719533Z","end":"2026-02-26T20:14:41.83793Z","steps":["trace[1190444997] 'process raft request' (duration: 118.278889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.411344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565342517070664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:14:44.630496Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.16075585s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T20:14:44.630823Z","caller":"traceutil/trace.go:171","msg":"trace[1995780631] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:868; }","duration":"720.106271ms","start":"2026-02-26T20:14:43.910695Z","end":"2026-02-26T20:14:44.630801Z","steps":["trace[1995780631] 'read index received' (duration: 719.918777ms)","trace[1995780631] 'applied index is now lower than readState.Index' (duration: 186.434µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:14:44.630883Z","caller":"traceutil/trace.go:171","msg":"trace[364953549] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"1.161178989s","start":"2026-02-26T20:14:43.469675Z","end":"2026-02-26T20:14:44.630854Z","steps":["trace[364953549] 'process raft request' (duration: 1.160989615s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.631018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:43.469659Z","time spent":"1.161293831s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:14:44.631036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.312086ms","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-26T20:14:44.631089Z","caller":"traceutil/trace.go:171","msg":"trace[1461909083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:833; }","duration":"720.420888ms","start":"2026-02-26T20:14:43.910652Z","end":"2026-02-26T20:14:44.631073Z","steps":["trace[1461909083] 'agreement among raft nodes before linearized reading' (duration: 720.251514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.631131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:43.910635Z","time spent":"720.48666ms","remote":"127.0.0.1:53838","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-26T20:14:44.631211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.348829ms","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-26T20:14:44.631239Z","caller":"traceutil/trace.go:171","msg":"trace[1475937435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:833; }","duration":"717.404591ms","start":"2026-02-26T20:14:43.913827Z","end":"2026-02-26T20:14:44.631231Z","steps":["trace[1475937435] 'agreement among raft nodes before linearized reading' (duration: 717.286468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.631263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:43.913818Z","time spent":"717.440831ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T20:14:44.631316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.458138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:14:44.631333Z","caller":"traceutil/trace.go:171","msg":"trace[868872526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"350.507919ms","start":"2026-02-26T20:14:44.280819Z","end":"2026-02-26T20:14:44.631327Z","steps":["trace[868872526] 'agreement among raft nodes before linearized reading' (duration: 350.483278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.631347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:44.2808Z","time spent":"350.544139ms","remote":"127.0.0.1:53586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:14:44.631418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.739431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T20:14:44.631496Z","caller":"traceutil/trace.go:171","msg":"trace[36945439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:833; }","duration":"108.848573ms","start":"2026-02-26T20:14:44.52263Z","end":"2026-02-26T20:14:44.631479Z","steps":["trace[36945439] 'agreement among raft nodes before linearized reading' (duration: 108.663029ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:44.958753Z","caller":"traceutil/trace.go:171","msg":"trace[1936403708] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:869; }","duration":"284.855596ms","start":"2026-02-26T20:14:44.673876Z","end":"2026-02-26T20:14:44.958732Z","steps":["trace[1936403708] 'read index received' (duration: 282.329068ms)","trace[1936403708] 'applied index is now lower than readState.Index' (duration: 2.525668ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:14:44.958823Z","caller":"traceutil/trace.go:171","msg":"trace[1790391341] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"324.537639ms","start":"2026-02-26T20:14:44.634273Z","end":"2026-02-26T20:14:44.958811Z","steps":["trace[1790391341] 'process raft request' (duration: 321.918709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.95891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:44.634241Z","time spent":"324.60913ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:14:44.958955Z","caller":"traceutil/trace.go:171","msg":"trace[526185556] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"323.352761ms","start":"2026-02-26T20:14:44.635575Z","end":"2026-02-26T20:14:44.958928Z","steps":["trace[526185556] 'process raft request' (duration: 323.126316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.959012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.121031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T20:14:44.958866Z","caller":"traceutil/trace.go:171","msg":"trace[412816053] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"323.72792ms","start":"2026-02-26T20:14:44.635129Z","end":"2026-02-26T20:14:44.958856Z","steps":["trace[412816053] 'process raft request' (duration: 323.528285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.959069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.728693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:14:44.959081Z","caller":"traceutil/trace.go:171","msg":"trace[1807905540] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:836; }","duration":"285.233354ms","start":"2026-02-26T20:14:44.67383Z","end":"2026-02-26T20:14:44.959063Z","steps":["trace[1807905540] 'agreement among raft nodes before linearized reading' (duration: 285.06449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.959118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:44.635562Z","time spent":"323.500604ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:14:44.959125Z","caller":"traceutil/trace.go:171","msg":"trace[1690905088] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:836; }","duration":"229.829415ms","start":"2026-02-26T20:14:44.729279Z","end":"2026-02-26T20:14:44.959109Z","steps":["trace[1690905088] 'agreement among raft nodes before linearized reading' (duration: 229.708403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.959129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:14:44.63511Z","time spent":"323.971415ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:14:44.959178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.423474ms","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-26T20:14:44.959203Z","caller":"traceutil/trace.go:171","msg":"trace[1224743956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:836; }","duration":"199.483835ms","start":"2026-02-26T20:14:44.759711Z","end":"2026-02-26T20:14:44.959195Z","steps":["trace[1224743956] 'agreement among raft nodes before linearized reading' (duration: 199.376753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:44.959285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.028901ms","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-26T20:14:44.9593Z","caller":"traceutil/trace.go:171","msg":"trace[206521422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:836; }","duration":"205.042441ms","start":"2026-02-26T20:14:44.754252Z","end":"2026-02-26T20:14:44.959294Z","steps":["trace[206521422] 'agreement among raft nodes before linearized reading' (duration: 204.99772ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:57.165818Z","caller":"traceutil/trace.go:171","msg":"trace[343246204] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"122.791152ms","start":"2026-02-26T20:14:57.043002Z","end":"2026-02-26T20:14:57.165793Z","steps":["trace[343246204] 'process raft request' (duration: 122.576557ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:57.212814Z","caller":"traceutil/trace.go:171","msg":"trace[843409762] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"168.969752ms","start":"2026-02-26T20:14:57.043812Z","end":"2026-02-26T20:14:57.212782Z","steps":["trace[843409762] 'process raft request' (duration: 168.887141ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:57.212877Z","caller":"traceutil/trace.go:171","msg":"trace[236611608] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"169.76266ms","start":"2026-02-26T20:14:57.043077Z","end":"2026-02-26T20:14:57.212839Z","steps":["trace[236611608] 'process raft request' (duration: 169.442462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:14:57.213282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.369421ms","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-26T20:14:57.213351Z","caller":"traceutil/trace.go:171","msg":"trace[76993399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:870; }","duration":"169.507474ms","start":"2026-02-26T20:14:57.043833Z","end":"2026-02-26T20:14:57.21334Z","steps":["trace[76993399] 'agreement among raft nodes before linearized reading' (duration: 169.248698ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:57.213047Z","caller":"traceutil/trace.go:171","msg":"trace[866746976] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"166.625599ms","start":"2026-02-26T20:14:57.046405Z","end":"2026-02-26T20:14:57.213031Z","steps":["trace[866746976] 'process raft request' (duration: 166.337353ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:14:57.213571Z","caller":"traceutil/trace.go:171","msg":"trace[1813421408] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:905; }","duration":"169.106796ms","start":"2026-02-26T20:14:57.043864Z","end":"2026-02-26T20:14:57.212971Z","steps":["trace[1813421408] 'read index received' (duration: 121.764109ms)","trace[1813421408] 'applied index is now lower than readState.Index' (duration: 47.340967ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:15:00.534408Z","caller":"traceutil/trace.go:171","msg":"trace[508826114] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"144.566386ms","start":"2026-02-26T20:15:00.389817Z","end":"2026-02-26T20:15:00.534384Z","steps":["trace[508826114] 'process raft request' (duration: 144.404682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:01.749668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.139318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-02-26T20:15:01.749758Z","caller":"traceutil/trace.go:171","msg":"trace[546090995] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:897; }","duration":"521.347222ms","start":"2026-02-26T20:15:01.228389Z","end":"2026-02-26T20:15:01.749737Z","steps":["trace[546090995] 'range keys from in-memory index tree' (duration: 520.920683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:01.749796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.228322Z","time spent":"521.466036ms","remote":"127.0.0.1:53838","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-26T20:15:01.750052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.001818ms","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-26T20:15:01.750126Z","caller":"traceutil/trace.go:171","msg":"trace[1383218115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:897; }","duration":"570.106211ms","start":"2026-02-26T20:15:01.18Z","end":"2026-02-26T20:15:01.750106Z","steps":["trace[1383218115] 'range keys from in-memory index tree' (duration: 569.765994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:01.750162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.179976Z","time spent":"570.178663ms","remote":"127.0.0.1:53838","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-26T20:15:01.750341Z","caller":"traceutil/trace.go:171","msg":"trace[1299787743] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"475.465309ms","start":"2026-02-26T20:15:01.274865Z","end":"2026-02-26T20:15:01.75033Z","steps":["trace[1299787743] 'process raft request' (duration: 471.676853ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:15:01.75035Z","caller":"traceutil/trace.go:171","msg":"trace[243720345] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"375.416645ms","start":"2026-02-26T20:15:01.374914Z","end":"2026-02-26T20:15:01.750331Z","steps":["trace[243720345] 'read index received' (duration: 371.6771ms)","trace[243720345] 'applied index is now lower than readState.Index' (duration: 3.737615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:15:01.750435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.274838Z","time spent":"475.534521ms","remote":"127.0.0.1:53650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:15:01.750474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.551738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T20:15:01.75042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.429444ms","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-26T20:15:01.750513Z","caller":"traceutil/trace.go:171","msg":"trace[1037479401] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:898; }","duration":"375.63682ms","start":"2026-02-26T20:15:01.374867Z","end":"2026-02-26T20:15:01.750504Z","steps":["trace[1037479401] 'agreement among raft nodes before linearized reading' (duration: 375.525657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:01.750551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.374845Z","time spent":"375.697701ms","remote":"127.0.0.1:53980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-02-26T20:15:01.750546Z","caller":"traceutil/trace.go:171","msg":"trace[1023888356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"521.612109ms","start":"2026-02-26T20:15:01.228907Z","end":"2026-02-26T20:15:01.750519Z","steps":["trace[1023888356] 'range keys from in-memory index tree' (duration: 521.095757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:01.750603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.228897Z","time spent":"521.695201ms","remote":"127.0.0.1:53838","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":"warn","ts":"2026-02-26T20:15:01.750052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.622336ms","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-26T20:15:01.751189Z","caller":"traceutil/trace.go:171","msg":"trace[928266091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"521.805874ms","start":"2026-02-26T20:15:01.22935Z","end":"2026-02-26T20:15:01.751156Z","steps":["trace[928266091] 'range keys from in-memory index tree' (duration: 520.242518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:01.751298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.229339Z","time spent":"521.921007ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T20:15:01.750053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.035464ms","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-26T20:15:01.751497Z","caller":"traceutil/trace.go:171","msg":"trace[1445035548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:897; }","duration":"518.54939ms","start":"2026-02-26T20:15:01.232933Z","end":"2026-02-26T20:15:01.751483Z","steps":["trace[1445035548] 'range keys from in-memory index tree' (duration: 516.872351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:01.751531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.232922Z","time spent":"518.59954ms","remote":"127.0.0.1:53838","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-26T20:15:02.174957Z","caller":"traceutil/trace.go:171","msg":"trace[1677261464] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"419.661961ms","start":"2026-02-26T20:15:01.755246Z","end":"2026-02-26T20:15:02.174908Z","steps":["trace[1677261464] 'process raft request' (duration: 363.384822ms)","trace[1677261464] 'compare' (duration: 56.036364ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:15:02.175114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.755224Z","time spent":"419.813005ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:15:02.247159Z","caller":"traceutil/trace.go:171","msg":"trace[8144673] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"491.267149ms","start":"2026-02-26T20:15:01.755867Z","end":"2026-02-26T20:15:02.247134Z","steps":["trace[8144673] 'process raft request' (duration: 491.192578ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:15:02.247171Z","caller":"traceutil/trace.go:171","msg":"trace[1674910666] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"491.388722ms","start":"2026-02-26T20:15:01.755754Z","end":"2026-02-26T20:15:02.247142Z","steps":["trace[1674910666] 'process raft request' (duration: 491.195748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:02.247302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.755844Z","time spent":"491.389123ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:15:02.247334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.755744Z","time spent":"491.506475ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:15:02.247345Z","caller":"traceutil/trace.go:171","msg":"trace[354372186] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"490.487412ms","start":"2026-02-26T20:15:01.756842Z","end":"2026-02-26T20:15:02.247329Z","steps":["trace[354372186] 'process raft request' (duration: 490.42064ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:15:02.247427Z","caller":"traceutil/trace.go:171","msg":"trace[1480997135] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"491.509995ms","start":"2026-02-26T20:15:01.755909Z","end":"2026-02-26T20:15:02.247419Z","steps":["trace[1480997135] 'process raft request' (duration: 491.197178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:02.247442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.756831Z","time spent":"490.559003ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:15:02.24748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:01.755883Z","time spent":"491.562936ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:15:02.478174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.772893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:15:02.478247Z","caller":"traceutil/trace.go:171","msg":"trace[1663967966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:903; }","duration":"196.892396ms","start":"2026-02-26T20:15:02.281338Z","end":"2026-02-26T20:15:02.47823Z","steps":["trace[1663967966] 'range keys from in-memory index tree' (duration: 196.699851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:02.478309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.896509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:15:02.478418Z","caller":"traceutil/trace.go:171","msg":"trace[1180419960] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"157.348418ms","start":"2026-02-26T20:15:02.32105Z","end":"2026-02-26T20:15:02.478398Z","steps":["trace[1180419960] 'process raft request' (duration: 54.271934ms)","trace[1180419960] 'compare' (duration: 102.736536ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:15:03.353291Z","caller":"traceutil/trace.go:171","msg":"trace[569103913] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"319.014843ms","start":"2026-02-26T20:15:03.034252Z","end":"2026-02-26T20:15:03.353267Z","steps":["trace[569103913] 'process raft request' (duration: 318.827647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:03.353448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:03.034229Z","time spent":"319.148396ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:15:03.414375Z","caller":"traceutil/trace.go:171","msg":"trace[372754613] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:945; }","duration":"132.590224ms","start":"2026-02-26T20:15:03.281761Z","end":"2026-02-26T20:15:03.414351Z","steps":["trace[372754613] 'read index received' (duration: 71.305611ms)","trace[372754613] 'applied index is now lower than readState.Index' (duration: 61.283433ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:15:03.414545Z","caller":"traceutil/trace.go:171","msg":"trace[1178361161] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"333.00053ms","start":"2026-02-26T20:15:03.081534Z","end":"2026-02-26T20:15:03.414535Z","steps":["trace[1178361161] 'process raft request' (duration: 332.706523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:03.414643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:03.08151Z","time spent":"333.065561ms","remote":"127.0.0.1:53650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:15:03.414932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.163518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:15:03.414968Z","caller":"traceutil/trace.go:171","msg":"trace[2102965273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"133.23691ms","start":"2026-02-26T20:15:03.28172Z","end":"2026-02-26T20:15:03.414957Z","steps":["trace[2102965273] 'agreement among raft nodes before linearized reading' (duration: 133.143948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:03.941049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.958564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:15:03.941208Z","caller":"traceutil/trace.go:171","msg":"trace[1287900420] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"580.100758ms","start":"2026-02-26T20:15:03.36108Z","end":"2026-02-26T20:15:03.941181Z","steps":["trace[1287900420] 'process raft request' (duration: 354.767815ms)","trace[1287900420] 'compare' (duration: 224.584206ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:15:03.941319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:03.36106Z","time spent":"580.203101ms","remote":"127.0.0.1:53650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:15:03.941365Z","caller":"traceutil/trace.go:171","msg":"trace[1346880019] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"526.832657ms","start":"2026-02-26T20:15:03.414502Z","end":"2026-02-26T20:15:03.941335Z","steps":["trace[1346880019] 'read index received' (duration: 301.359342ms)","trace[1346880019] 'applied index is now lower than readState.Index' (duration: 225.470775ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:15:03.941421Z","caller":"traceutil/trace.go:171","msg":"trace[830240623] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"520.257657ms","start":"2026-02-26T20:15:03.421138Z","end":"2026-02-26T20:15:03.941396Z","steps":["trace[830240623] 'process raft request' (duration: 520.029392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:03.941538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:03.421113Z","time spent":"520.35384ms","remote":"127.0.0.1:53650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:15:03.941692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.536004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-26T20:15:03.941754Z","caller":"traceutil/trace.go:171","msg":"trace[1146584954] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:909; }","duration":"582.650847ms","start":"2026-02-26T20:15:03.359092Z","end":"2026-02-26T20:15:03.941743Z","steps":["trace[1146584954] 'agreement among raft nodes before linearized reading' (duration: 582.3686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:03.941794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:03.359067Z","time spent":"582.718359ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-26T20:15:04.470513Z","caller":"traceutil/trace.go:171","msg":"trace[655244592] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"291.527538ms","start":"2026-02-26T20:15:04.178961Z","end":"2026-02-26T20:15:04.470488Z","steps":["trace[655244592] 'read index received' (duration: 291.284973ms)","trace[655244592] 'applied index is now lower than readState.Index' (duration: 241.715µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:15:04.470638Z","caller":"traceutil/trace.go:171","msg":"trace[1479827468] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"523.666166ms","start":"2026-02-26T20:15:03.946957Z","end":"2026-02-26T20:15:04.470623Z","steps":["trace[1479827468] 'process raft request' (duration: 523.3839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:04.470759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:03.946933Z","time spent":"523.734277ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:15:04.471031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.217443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:15:04.471101Z","caller":"traceutil/trace.go:171","msg":"trace[1412831726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"189.304374ms","start":"2026-02-26T20:15:04.281777Z","end":"2026-02-26T20:15:04.471082Z","steps":["trace[1412831726] 'agreement among raft nodes before linearized reading' (duration: 189.204782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:04.471112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.854333ms","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-26T20:15:04.471112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.136192ms","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-26T20:15:04.471178Z","caller":"traceutil/trace.go:171","msg":"trace[2396295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"217.941515ms","start":"2026-02-26T20:15:04.25322Z","end":"2026-02-26T20:15:04.471162Z","steps":["trace[2396295] 'agreement among raft nodes before linearized reading' (duration: 217.73053ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:15:04.471191Z","caller":"traceutil/trace.go:171","msg":"trace[1884026389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"292.253185ms","start":"2026-02-26T20:15:04.178924Z","end":"2026-02-26T20:15:04.471177Z","steps":["trace[1884026389] 'agreement among raft nodes before linearized reading' (duration: 292.031701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:04.47126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.878247ms","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-26T20:15:04.471308Z","caller":"traceutil/trace.go:171","msg":"trace[1123561147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"218.926768ms","start":"2026-02-26T20:15:04.25237Z","end":"2026-02-26T20:15:04.471297Z","steps":["trace[1123561147] 'agreement among raft nodes before linearized reading' (duration: 218.774655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:04.471432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.316757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:489"} {"level":"info","ts":"2026-02-26T20:15:04.471479Z","caller":"traceutil/trace.go:171","msg":"trace[1873902284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"219.363248ms","start":"2026-02-26T20:15:04.252104Z","end":"2026-02-26T20:15:04.471467Z","steps":["trace[1873902284] 'agreement among raft nodes before linearized reading' (duration: 219.214335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:04.471558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.774737ms","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-26T20:15:04.471606Z","caller":"traceutil/trace.go:171","msg":"trace[426209801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:910; }","duration":"219.844459ms","start":"2026-02-26T20:15:04.251751Z","end":"2026-02-26T20:15:04.471596Z","steps":["trace[426209801] 'agreement among raft nodes before linearized reading' (duration: 219.080791ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:15:05.125816Z","caller":"traceutil/trace.go:171","msg":"trace[1866139700] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"318.149492ms","start":"2026-02-26T20:15:04.807639Z","end":"2026-02-26T20:15:05.125788Z","steps":["trace[1866139700] 'process raft request' (duration: 317.748903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:05.125982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:04.807619Z","time spent":"318.279456ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:15:06.125122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.328254ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T20:15:06.808958Z","caller":"traceutil/trace.go:171","msg":"trace[402081935] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"234.389929ms","start":"2026-02-26T20:15:06.574552Z","end":"2026-02-26T20:15:06.808942Z","steps":["trace[402081935] 'read index received' (duration: 234.239266ms)","trace[402081935] 'applied index is now lower than readState.Index' (duration: 147.863µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:15:06.809013Z","caller":"traceutil/trace.go:171","msg":"trace[950604232] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"237.840337ms","start":"2026-02-26T20:15:06.571143Z","end":"2026-02-26T20:15:06.808983Z","steps":["trace[950604232] 'process raft request' (duration: 237.689494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:06.809156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.487705ms","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-26T20:15:06.809179Z","caller":"traceutil/trace.go:171","msg":"trace[785845321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"232.566267ms","start":"2026-02-26T20:15:06.576606Z","end":"2026-02-26T20:15:06.809173Z","steps":["trace[785845321] 'agreement among raft nodes before linearized reading' (duration: 232.475815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:06.809219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.246475ms","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-26T20:15:06.809291Z","caller":"traceutil/trace.go:171","msg":"trace[1863527731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"234.44424ms","start":"2026-02-26T20:15:06.574837Z","end":"2026-02-26T20:15:06.809281Z","steps":["trace[1863527731] 'agreement among raft nodes before linearized reading' (duration: 234.254946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:06.809275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.278006ms","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-26T20:15:06.809345Z","caller":"traceutil/trace.go:171","msg":"trace[1139502504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"234.409579ms","start":"2026-02-26T20:15:06.574919Z","end":"2026-02-26T20:15:06.809329Z","steps":["trace[1139502504] 'agreement among raft nodes before linearized reading' (duration: 234.162153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:06.809418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.86708ms","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-26T20:15:06.80944Z","caller":"traceutil/trace.go:171","msg":"trace[605992094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:917; }","duration":"234.910751ms","start":"2026-02-26T20:15:06.574523Z","end":"2026-02-26T20:15:06.809434Z","steps":["trace[605992094] 'agreement among raft nodes before linearized reading' (duration: 234.499302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:15.342987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565342517070975,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:15:15.844084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565342517070975,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:15:16.344884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296565342517070975,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:15:16.387806Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.567521617s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T20:15:16.389127Z","caller":"traceutil/trace.go:171","msg":"trace[1805862933] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.56894836s","start":"2026-02-26T20:15:14.820155Z","end":"2026-02-26T20:15:16.389103Z","steps":["trace[1805862933] 'process raft request' (duration: 1.568801767s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:16.389273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:14.820132Z","time spent":"1.569064322s","remote":"127.0.0.1:53734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6610,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:15:16.588637Z","caller":"traceutil/trace.go:171","msg":"trace[987710334] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.176319924s","start":"2026-02-26T20:15:15.412286Z","end":"2026-02-26T20:15:16.588606Z","steps":["trace[987710334] 'process raft request' (duration: 1.157838744s)","trace[987710334] 'compare' (duration: 18.293616ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:15:16.588802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:15.412259Z","time spent":"1.176460377s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:15:16.599385Z","caller":"traceutil/trace.go:171","msg":"trace[1959028795] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1019; }","duration":"1.756602816s","start":"2026-02-26T20:15:14.842758Z","end":"2026-02-26T20:15:16.599361Z","steps":["trace[1959028795] 'read index received' (duration: 1.546319155s)","trace[1959028795] 'applied index is now lower than readState.Index' (duration: 210.282371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:15:16.599784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570975466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T20:15:16.599782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319469238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:15:16.599859Z","caller":"traceutil/trace.go:171","msg":"trace[948002805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"1.319592981s","start":"2026-02-26T20:15:15.280246Z","end":"2026-02-26T20:15:16.599839Z","steps":["trace[948002805] 'agreement among raft nodes before linearized reading' (duration: 1.319469288s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:16.599952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:15.280228Z","time spent":"1.319713663s","remote":"127.0.0.1:53586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:15:16.599803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.041361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T20:15:16.600168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.702381683s","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-26T20:15:16.600222Z","caller":"traceutil/trace.go:171","msg":"trace[188151535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"267.491211ms","start":"2026-02-26T20:15:16.332701Z","end":"2026-02-26T20:15:16.600193Z","steps":["trace[188151535] 'agreement among raft nodes before linearized reading' (duration: 267.044321ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:15:16.600209Z","caller":"traceutil/trace.go:171","msg":"trace[300549549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"1.702531227s","start":"2026-02-26T20:15:14.897665Z","end":"2026-02-26T20:15:16.600196Z","steps":["trace[300549549] 'agreement among raft nodes before linearized reading' (duration: 1.702243841s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:16.60035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.457076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:15:16.599832Z","caller":"traceutil/trace.go:171","msg":"trace[1751076743] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:979; }","duration":"1.57109825s","start":"2026-02-26T20:15:15.028721Z","end":"2026-02-26T20:15:16.59982Z","steps":["trace[1751076743] 'agreement among raft nodes before linearized reading' (duration: 1.571005418s)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:15:16.600384Z","caller":"traceutil/trace.go:171","msg":"trace[1587437633] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:979; }","duration":"452.559208ms","start":"2026-02-26T20:15:16.147816Z","end":"2026-02-26T20:15:16.600375Z","steps":["trace[1587437633] 'agreement among raft nodes before linearized reading' (duration: 452.490617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:16.600413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:16.147796Z","time spent":"452.60827ms","remote":"127.0.0.1:53790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-26T20:15:16.600417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:15.028699Z","time spent":"1.571697953s","remote":"127.0.0.1:54016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-26T20:15:16.600584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705230138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-26T20:15:16.60068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706675771s","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-26T20:15:16.60072Z","caller":"traceutil/trace.go:171","msg":"trace[1117847048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"1.706760263s","start":"2026-02-26T20:15:14.893949Z","end":"2026-02-26T20:15:16.60071Z","steps":["trace[1117847048] 'agreement among raft nodes before linearized reading' (duration: 1.70666001s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:16.600752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:14.893924Z","time spent":"1.706821114s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T20:15:16.600657Z","caller":"traceutil/trace.go:171","msg":"trace[2053005015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"1.70532689s","start":"2026-02-26T20:15:14.895311Z","end":"2026-02-26T20:15:16.600638Z","steps":["trace[2053005015] 'agreement among raft nodes before linearized reading' (duration: 1.705131546s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:16.600866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758108762s","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-26T20:15:16.60091Z","caller":"traceutil/trace.go:171","msg":"trace[1452044619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"1.758184113s","start":"2026-02-26T20:15:14.842716Z","end":"2026-02-26T20:15:16.6009Z","steps":["trace[1452044619] 'agreement among raft nodes before linearized reading' (duration: 1.75674751s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:16.60094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:14.842704Z","time spent":"1.758229204s","remote":"127.0.0.1:53838","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-26T20:15:16.600845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:14.895303Z","time spent":"1.705529484s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T20:15:16.601204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:14.897634Z","time spent":"1.70356222s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T20:15:16.601539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159681236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-26T20:15:16.601847Z","caller":"traceutil/trace.go:171","msg":"trace[1816120585] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:979; }","duration":"1.160015783s","start":"2026-02-26T20:15:15.441813Z","end":"2026-02-26T20:15:16.601829Z","steps":["trace[1816120585] 'agreement among raft nodes before linearized reading' (duration: 1.159522192s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:16.601887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:15.441788Z","time spent":"1.160086405s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-26T20:15:16.602419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.70972648s","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-26T20:15:16.602505Z","caller":"traceutil/trace.go:171","msg":"trace[1217863343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"1.712424821s","start":"2026-02-26T20:15:14.890064Z","end":"2026-02-26T20:15:16.602489Z","steps":["trace[1217863343] 'agreement among raft nodes before linearized reading' (duration: 1.709609397s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:15:16.602648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:15:14.890049Z","time spent":"1.712583016s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T20:17:27.407759Z","caller":"traceutil/trace.go:171","msg":"trace[572954499] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"115.610398ms","start":"2026-02-26T20:17:27.292133Z","end":"2026-02-26T20:17:27.407743Z","steps":["trace[572954499] 'process raft request' (duration: 29.809203ms)","trace[572954499] 'compare' (duration: 85.684243ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:17:27.40776Z","caller":"traceutil/trace.go:171","msg":"trace[631850203] linearizableReadLoop","detail":"{readStateIndex:1583; appliedIndex:1582; }","duration":"106.23663ms","start":"2026-02-26T20:17:27.301495Z","end":"2026-02-26T20:17:27.407732Z","steps":["trace[631850203] 'read index received' (duration: 20.457215ms)","trace[631850203] 'applied index is now lower than readState.Index' (duration: 85.778445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:17:27.407898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.380364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-26T20:17:27.407955Z","caller":"traceutil/trace.go:171","msg":"trace[892371924] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1514; }","duration":"106.490235ms","start":"2026-02-26T20:17:27.301453Z","end":"2026-02-26T20:17:27.407943Z","steps":["trace[892371924] 'agreement among raft nodes before linearized reading' (duration: 106.311692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:17:27.40798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.402321ms","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-26T20:17:27.408004Z","caller":"traceutil/trace.go:171","msg":"trace[1202019004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1514; }","duration":"105.434032ms","start":"2026-02-26T20:17:27.302563Z","end":"2026-02-26T20:17:27.407997Z","steps":["trace[1202019004] 'agreement among raft nodes before linearized reading' (duration: 105.36301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:17:27.408008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.369764ms","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-26T20:17:27.408041Z","caller":"traceutil/trace.go:171","msg":"trace[1104707899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1514; }","duration":"106.435214ms","start":"2026-02-26T20:17:27.301594Z","end":"2026-02-26T20:17:27.408029Z","steps":["trace[1104707899] 'agreement among raft nodes before linearized reading' (duration: 106.326462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:17:27.408078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.536854ms","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-26T20:17:27.408095Z","caller":"traceutil/trace.go:171","msg":"trace[1999557014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1514; }","duration":"105.559525ms","start":"2026-02-26T20:17:27.302529Z","end":"2026-02-26T20:17:27.408088Z","steps":["trace[1999557014] 'agreement among raft nodes before linearized reading' (duration: 105.521034ms)"],"step_count":1}