{"level":"warn","ts":"2026-03-02T18:29:49.311369Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T18:29:49.311505Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.67: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.67:2380","--initial-cluster=instance=https://199.204.45.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.67: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-03-02T18:29:49.311877Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-02T18:29:49.311912Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-03-02T18:29:49.311945Z","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-03-02T18:29:49.314106Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"]} {"level":"info","ts":"2026-03-02T18:29:49.314331Z","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.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.67: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-03-02T18:29:49.321157Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.387324ms"} {"level":"info","ts":"2026-03-02T18:29:49.327215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cddd08233fdda28a","cluster-id":"72e3bf4964adc703"} {"level":"info","ts":"2026-03-02T18:29:49.327294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T18:29:49.32744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 0"} {"level":"info","ts":"2026-03-02T18:29:49.327458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cddd08233fdda28a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T18:29:49.327472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became follower at term 1"} {"level":"info","ts":"2026-03-02T18:29:49.327573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"warn","ts":"2026-03-02T18:29:49.335391Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T18:29:49.336981Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T18:29:49.339668Z","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-03-02T18:29:49.342009Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cddd08233fdda28a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T18:29:49.345218Z","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-03-02T18:29:49.345027Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cddd08233fdda28a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T18:29:49.345423Z","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-03-02T18:29:49.345488Z","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-03-02T18:29:49.346068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a switched to configuration voters=(14834021695116649098)"} {"level":"info","ts":"2026-03-02T18:29:49.346641Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","added-peer-id":"cddd08233fdda28a","added-peer-peer-urls":["https://199.204.45.67:2380"]} {"level":"info","ts":"2026-03-02T18:29:49.350116Z","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-03-02T18:29:49.350154Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-03-02T18:29:49.350428Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.67:2380"} {"level":"info","ts":"2026-03-02T18:29:49.350814Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cddd08233fdda28a","initial-advertise-peer-urls":["https://199.204.45.67:2380"],"listen-peer-urls":["https://199.204.45.67:2380"],"advertise-client-urls":["https://199.204.45.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T18:29:49.350868Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T18:29:50.028127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T18:29:50.028197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T18:29:50.028248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgPreVoteResp from cddd08233fdda28a at term 1"} {"level":"info","ts":"2026-03-02T18:29:50.028277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became candidate at term 2"} {"level":"info","ts":"2026-03-02T18:29:50.028296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a received MsgVoteResp from cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-03-02T18:29:50.028319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cddd08233fdda28a became leader at term 2"} {"level":"info","ts":"2026-03-02T18:29:50.028338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cddd08233fdda28a elected leader cddd08233fdda28a at term 2"} {"level":"info","ts":"2026-03-02T18:29:50.029507Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cddd08233fdda28a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.67:2379]}","request-path":"/0/members/cddd08233fdda28a/attributes","cluster-id":"72e3bf4964adc703","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T18:29:50.029532Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:29:50.029568Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T18:29:50.029609Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T18:29:50.030922Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T18:29:50.030967Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T18:29:50.031081Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"72e3bf4964adc703","local-member-id":"cddd08233fdda28a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:29:50.031168Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:29:50.031193Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T18:29:50.032132Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.67:2379"} {"level":"info","ts":"2026-03-02T18:29:50.032543Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-02T18:29:51.86149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346059858153347,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:29:52.36224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346059858153347,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:29:52.43065Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.070960096s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-02T18:29:52.506593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149289652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.67\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T18:29:52.50667Z","caller":"traceutil/trace.go:171","msg":"trace[1032004346] range","detail":"{range_begin:/registry/masterleases/199.204.45.67; range_end:; response_count:0; response_revision:4; }","duration":"1.149409776s","start":"2026-03-02T18:29:51.357241Z","end":"2026-03-02T18:29:52.506651Z","steps":["trace[1032004346] 'range keys from in-memory index tree' (duration: 1.149215061s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:52.506719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.357233Z","time spent":"1.149476678s","remote":"127.0.0.1:42562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.67\" "} {"level":"warn","ts":"2026-03-02T18:29:52.50676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148144722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T18:29:52.506823Z","caller":"traceutil/trace.go:171","msg":"trace[1863947457] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:4; }","duration":"1.148232084s","start":"2026-03-02T18:29:51.358575Z","end":"2026-03-02T18:29:52.506807Z","steps":["trace[1863947457] 'range keys from in-memory index tree' (duration: 1.148075659s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:52.506873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.358564Z","time spent":"1.148298306s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-02T18:29:52.506862Z","caller":"traceutil/trace.go:171","msg":"trace[1646544802] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.147186386s","start":"2026-03-02T18:29:51.359627Z","end":"2026-03-02T18:29:52.506813Z","steps":["trace[1646544802] 'process raft request' (duration: 1.071402659s)","trace[1646544802] 'compare' (duration: 75.648173ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:52.507369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.359613Z","time spent":"1.147318489s","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T18:29:53.805491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.453381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-02T18:29:53.805853Z","caller":"traceutil/trace.go:171","msg":"trace[578149156] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"2.445633056s","start":"2026-03-02T18:29:51.360188Z","end":"2026-03-02T18:29:53.805821Z","steps":["trace[578149156] 'process raft request' (duration: 2.44539777s)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:53.805899Z","caller":"traceutil/trace.go:171","msg":"trace[1040858889] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"2.446005226s","start":"2026-03-02T18:29:51.359861Z","end":"2026-03-02T18:29:53.805866Z","steps":["trace[1040858889] 'process raft request' (duration: 1.729933718s)","trace[1040858889] 'compare' (duration: 715.003749ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:29:53.805936Z","caller":"traceutil/trace.go:171","msg":"trace[819033440] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"2.445484263s","start":"2026-03-02T18:29:51.360439Z","end":"2026-03-02T18:29:53.805923Z","steps":["trace[819033440] 'process raft request' (duration: 2.445237006s)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:53.805874Z","caller":"traceutil/trace.go:171","msg":"trace[1056884692] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"2.445192755s","start":"2026-03-02T18:29:51.360654Z","end":"2026-03-02T18:29:53.805846Z","steps":["trace[1056884692] 'process raft request' (duration: 2.445079342s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.805992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.359836Z","time spent":"2.44611943s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T18:29:53.806177Z","caller":"traceutil/trace.go:171","msg":"trace[1204516825] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"2.424911231s","start":"2026-03-02T18:29:51.381178Z","end":"2026-03-02T18:29:53.80609Z","steps":["trace[1204516825] 'process raft request' (duration: 2.424588143s)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:53.806262Z","caller":"traceutil/trace.go:171","msg":"trace[1609004318] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"2.445655447s","start":"2026-03-02T18:29:51.360523Z","end":"2026-03-02T18:29:53.806179Z","steps":["trace[1609004318] 'process raft request' (duration: 2.445186034s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.806338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.36016Z","time spent":"2.44578641s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T18:29:53.806369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.360644Z","time spent":"2.445367619s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T18:29:53.806342Z","caller":"traceutil/trace.go:171","msg":"trace[1092441774] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:7; }","duration":"2.44538812s","start":"2026-03-02T18:29:51.360937Z","end":"2026-03-02T18:29:53.806326Z","steps":["trace[1092441774] 'read index received' (duration: 1.070030011s)","trace[1092441774] 'applied index is now lower than readState.Index' (duration: 1.375356179s)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:29:53.806426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.381166Z","time spent":"2.425125968s","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T18:29:53.806193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.360425Z","time spent":"2.445545934s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T18:29:53.806515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.360513Z","time spent":"2.445911695s","remote":"127.0.0.1:43090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T18:29:53.806621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.445658127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T18:29:53.806661Z","caller":"traceutil/trace.go:171","msg":"trace[2064318417] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"2.44575834s","start":"2026-03-02T18:29:51.360891Z","end":"2026-03-02T18:29:53.806649Z","steps":["trace[2064318417] 'agreement among raft nodes before linearized reading' (duration: 2.445601065s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.8067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.36088Z","time spent":"2.445811572s","remote":"127.0.0.1:42632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-03-02T18:29:53.80676Z","caller":"traceutil/trace.go:171","msg":"trace[606994590] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.295322395s","start":"2026-03-02T18:29:52.511426Z","end":"2026-03-02T18:29:53.806748Z","steps":["trace[606994590] 'process raft request' (duration: 1.29437592s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.806826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:52.511403Z","time spent":"1.295393608s","remote":"127.0.0.1:42996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T18:29:53.807026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.395241383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-02T18:29:53.807021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.376937483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-02T18:29:53.807026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548849159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T18:29:53.807067Z","caller":"traceutil/trace.go:171","msg":"trace[1566087716] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"2.395316275s","start":"2026-03-02T18:29:51.411739Z","end":"2026-03-02T18:29:53.807056Z","steps":["trace[1566087716] 'agreement among raft nodes before linearized reading' (duration: 2.395251043s)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:29:53.807073Z","caller":"traceutil/trace.go:171","msg":"trace[1313945778] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:12; }","duration":"2.377026195s","start":"2026-03-02T18:29:51.430035Z","end":"2026-03-02T18:29:53.807061Z","steps":["trace[1313945778] 'agreement among raft nodes before linearized reading' (duration: 2.376951733s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.807087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296886238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-02T18:29:53.8071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.411719Z","time spent":"2.395372706s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-02T18:29:53.807103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.430019Z","time spent":"2.377076877s","remote":"127.0.0.1:42656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-03-02T18:29:53.807123Z","caller":"traceutil/trace.go:171","msg":"trace[1129837784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"1.296922659s","start":"2026-03-02T18:29:52.510187Z","end":"2026-03-02T18:29:53.80711Z","steps":["trace[1129837784] 'agreement among raft nodes before linearized reading' (duration: 1.296845367s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.807148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:52.510121Z","time spent":"1.297021552s","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-02T18:29:53.807091Z","caller":"traceutil/trace.go:171","msg":"trace[682437034] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:12; }","duration":"1.548962362s","start":"2026-03-02T18:29:52.25811Z","end":"2026-03-02T18:29:53.807072Z","steps":["trace[682437034] 'agreement among raft nodes before linearized reading' (duration: 1.548830219s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.807181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:52.258085Z","time spent":"1.549089596s","remote":"127.0.0.1:42910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-03-02T18:29:53.807107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.269204745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T18:29:53.807284Z","caller":"traceutil/trace.go:171","msg":"trace[357650895] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:12; }","duration":"2.269443901s","start":"2026-03-02T18:29:51.537816Z","end":"2026-03-02T18:29:53.80726Z","steps":["trace[357650895] 'agreement among raft nodes before linearized reading' (duration: 2.269137383s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.807152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367488844s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T18:29:53.807355Z","caller":"traceutil/trace.go:171","msg":"trace[1649539508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"1.36772929s","start":"2026-03-02T18:29:52.439608Z","end":"2026-03-02T18:29:53.807338Z","steps":["trace[1649539508] 'agreement among raft nodes before linearized reading' (duration: 1.367509884s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.807406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:52.439589Z","time spent":"1.367796572s","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-02T18:29:53.807353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.537795Z","time spent":"2.269538414s","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-03-02T18:29:53.807469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549331933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T18:29:53.807501Z","caller":"traceutil/trace.go:171","msg":"trace[1078698194] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:12; }","duration":"1.549366484s","start":"2026-03-02T18:29:52.258125Z","end":"2026-03-02T18:29:53.807492Z","steps":["trace[1078698194] 'agreement among raft nodes before linearized reading' (duration: 1.549310962s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.807593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:52.258072Z","time spent":"1.549448806s","remote":"127.0.0.1:42888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-03-02T18:29:53.808064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.444119836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T18:29:53.808109Z","caller":"traceutil/trace.go:171","msg":"trace[1833633226] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"2.444188417s","start":"2026-03-02T18:29:51.363909Z","end":"2026-03-02T18:29:53.808097Z","steps":["trace[1833633226] 'agreement among raft nodes before linearized reading' (duration: 2.442982684s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:29:53.808142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:29:51.363901Z","time spent":"2.444234068s","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-03-02T18:30:22.128332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346059858154307,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:30:22.530282Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.038904082s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T18:30:22.530555Z","caller":"traceutil/trace.go:171","msg":"trace[531749396] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"902.744509ms","start":"2026-03-02T18:30:21.627792Z","end":"2026-03-02T18:30:22.530536Z","steps":["trace[531749396] 'read index received' (duration: 902.584365ms)","trace[531749396] 'applied index is now lower than readState.Index' (duration: 159.424µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:30:22.530808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.922817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:30:22.530814Z","caller":"traceutil/trace.go:171","msg":"trace[1210419979] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.039504359s","start":"2026-03-02T18:30:21.491274Z","end":"2026-03-02T18:30:22.530778Z","steps":["trace[1210419979] 'process raft request' (duration: 1.03916369s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:22.530931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.70167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-02T18:30:22.530937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.554866ms","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-03-02T18:30:22.530968Z","caller":"traceutil/trace.go:171","msg":"trace[531180095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"487.622108ms","start":"2026-03-02T18:30:22.04334Z","end":"2026-03-02T18:30:22.530963Z","steps":["trace[531180095] 'agreement among raft nodes before linearized reading' (duration: 487.563716ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:22.53097Z","caller":"traceutil/trace.go:171","msg":"trace[380267861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"421.771652ms","start":"2026-03-02T18:30:22.109187Z","end":"2026-03-02T18:30:22.530959Z","steps":["trace[380267861] 'agreement among raft nodes before linearized reading' (duration: 421.719111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:22.530994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:22.043321Z","time spent":"487.66709ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T18:30:22.530999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:22.109169Z","time spent":"421.820714ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T18:30:22.530885Z","caller":"traceutil/trace.go:171","msg":"trace[894438745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"660.012319ms","start":"2026-03-02T18:30:21.870855Z","end":"2026-03-02T18:30:22.530867Z","steps":["trace[894438745] 'agreement among raft nodes before linearized reading' (duration: 659.885336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:22.530883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.095143ms","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-03-02T18:30:22.531125Z","caller":"traceutil/trace.go:171","msg":"trace[1529276561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"465.388222ms","start":"2026-03-02T18:30:22.065729Z","end":"2026-03-02T18:30:22.531117Z","steps":["trace[1529276561] 'agreement among raft nodes before linearized reading' (duration: 465.106555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:22.53111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:21.870808Z","time spent":"660.275406ms","remote":"127.0.0.1:42530","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-03-02T18:30:22.531153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:22.065709Z","time spent":"465.436763ms","remote":"127.0.0.1:42818","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-03-02T18:30:22.530853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.033927ms","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-03-02T18:30:22.531212Z","caller":"traceutil/trace.go:171","msg":"trace[1841491162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"903.461779ms","start":"2026-03-02T18:30:21.627744Z","end":"2026-03-02T18:30:22.531206Z","steps":["trace[1841491162] 'agreement among raft nodes before linearized reading' (duration: 902.908184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:22.531234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:21.627723Z","time spent":"903.50628ms","remote":"127.0.0.1:42818","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-03-02T18:30:22.531403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:21.491257Z","time spent":"1.039691444s","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4611,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:30:24.713171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.689685ms","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-03-02T18:30:24.71324Z","caller":"traceutil/trace.go:171","msg":"trace[1458943332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"170.803919ms","start":"2026-03-02T18:30:24.542421Z","end":"2026-03-02T18:30:24.713225Z","steps":["trace[1458943332] 'range keys from in-memory index tree' (duration: 170.592532ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:24.713191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.560707ms","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-03-02T18:30:24.713255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.690148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-02T18:30:24.713171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.636416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-02T18:30:24.713326Z","caller":"traceutil/trace.go:171","msg":"trace[1108022605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"169.839602ms","start":"2026-03-02T18:30:24.543477Z","end":"2026-03-02T18:30:24.713317Z","steps":["trace[1108022605] 'range keys from in-memory index tree' (duration: 169.544274ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:24.713325Z","caller":"traceutil/trace.go:171","msg":"trace[1002545838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"169.807451ms","start":"2026-03-02T18:30:24.543501Z","end":"2026-03-02T18:30:24.713308Z","steps":["trace[1002545838] 'range keys from in-memory index tree' (duration: 169.608906ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:24.713325Z","caller":"traceutil/trace.go:171","msg":"trace[607739866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"168.743132ms","start":"2026-03-02T18:30:24.544554Z","end":"2026-03-02T18:30:24.713297Z","steps":["trace[607739866] 'range keys from in-memory index tree' (duration: 168.434874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:29.23034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346059858154353,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T18:30:29.529837Z","caller":"traceutil/trace.go:171","msg":"trace[127693726] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"800.407459ms","start":"2026-03-02T18:30:28.729407Z","end":"2026-03-02T18:30:29.529815Z","steps":["trace[127693726] 'read index received' (duration: 800.132711ms)","trace[127693726] 'applied index is now lower than readState.Index' (duration: 273.828µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:30:29.529978Z","caller":"traceutil/trace.go:171","msg":"trace[719444526] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"840.747969ms","start":"2026-03-02T18:30:28.689183Z","end":"2026-03-02T18:30:29.529931Z","steps":["trace[719444526] 'process raft request' (duration: 840.492042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:29.530014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.589904ms","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-03-02T18:30:29.53036Z","caller":"traceutil/trace.go:171","msg":"trace[1692745517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"800.988505ms","start":"2026-03-02T18:30:28.729358Z","end":"2026-03-02T18:30:29.530347Z","steps":["trace[1692745517] 'agreement among raft nodes before linearized reading' (duration: 800.589994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:29.53012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:28.689157Z","time spent":"840.889873ms","remote":"127.0.0.1:42718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:30:29.530134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.651303ms","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-03-02T18:30:29.530534Z","caller":"traceutil/trace.go:171","msg":"trace[2078692495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"791.067193ms","start":"2026-03-02T18:30:28.739436Z","end":"2026-03-02T18:30:29.530504Z","steps":["trace[2078692495] 'agreement among raft nodes before linearized reading' (duration: 790.607491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:29.530151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.426656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-02T18:30:29.530591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:28.739428Z","time spent":"791.147696ms","remote":"127.0.0.1:42818","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-03-02T18:30:29.530627Z","caller":"traceutil/trace.go:171","msg":"trace[1649751081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"794.94178ms","start":"2026-03-02T18:30:28.735651Z","end":"2026-03-02T18:30:29.530593Z","steps":["trace[1649751081] 'agreement among raft nodes before linearized reading' (duration: 794.379495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:29.530184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.013662ms","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-03-02T18:30:29.53073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:28.735622Z","time spent":"795.092384ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-02T18:30:29.530753Z","caller":"traceutil/trace.go:171","msg":"trace[1977248610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"791.590349ms","start":"2026-03-02T18:30:28.739137Z","end":"2026-03-02T18:30:29.530727Z","steps":["trace[1977248610] 'agreement among raft nodes before linearized reading' (duration: 790.967712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:29.530813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:28.739113Z","time spent":"791.68506ms","remote":"127.0.0.1:42818","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-03-02T18:30:29.530261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.615362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:30:29.530906Z","caller":"traceutil/trace.go:171","msg":"trace[190657231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"659.28294ms","start":"2026-03-02T18:30:28.871604Z","end":"2026-03-02T18:30:29.530886Z","steps":["trace[190657231] 'agreement among raft nodes before linearized reading' (duration: 658.633782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:29.530966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:28.871589Z","time spent":"659.358582ms","remote":"127.0.0.1:42536","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-03-02T18:30:29.531299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:28.729339Z","time spent":"801.947341ms","remote":"127.0.0.1:42818","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-03-02T18:30:29.731739Z","caller":"traceutil/trace.go:171","msg":"trace[79903458] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"196.52938ms","start":"2026-03-02T18:30:29.535184Z","end":"2026-03-02T18:30:29.731713Z","steps":["trace[79903458] 'process raft request' (duration: 121.884094ms)","trace[79903458] 'compare' (duration: 74.396459ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:30:29.732038Z","caller":"traceutil/trace.go:171","msg":"trace[931706991] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"196.700625ms","start":"2026-03-02T18:30:29.535304Z","end":"2026-03-02T18:30:29.732004Z","steps":["trace[931706991] 'process raft request' (duration: 196.368916ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:29.732084Z","caller":"traceutil/trace.go:171","msg":"trace[120807489] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"195.914152ms","start":"2026-03-02T18:30:29.53615Z","end":"2026-03-02T18:30:29.732064Z","steps":["trace[120807489] 'process raft request' (duration: 195.861751ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:29.7322Z","caller":"traceutil/trace.go:171","msg":"trace[2009580006] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"196.642403ms","start":"2026-03-02T18:30:29.535546Z","end":"2026-03-02T18:30:29.732188Z","steps":["trace[2009580006] 'process raft request' (duration: 196.354515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:30.492297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.356909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:30:30.492405Z","caller":"traceutil/trace.go:171","msg":"trace[1757215554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"620.485232ms","start":"2026-03-02T18:30:29.871901Z","end":"2026-03-02T18:30:30.492386Z","steps":["trace[1757215554] 'range keys from in-memory index tree' (duration: 620.234865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:30.492452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:29.871852Z","time spent":"620.589825ms","remote":"127.0.0.1:42530","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-03-02T18:30:30.557366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.468913ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-02T18:30:32.371217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346059858154367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:30:32.872315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346059858154367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-02T18:30:32.912254Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.172397893s","expected-duration":"1s"} {"level":"info","ts":"2026-03-02T18:30:32.91251Z","caller":"traceutil/trace.go:171","msg":"trace[452449265] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.172731832s","start":"2026-03-02T18:30:31.739761Z","end":"2026-03-02T18:30:32.912493Z","steps":["trace[452449265] 'process raft request' (duration: 1.1726277s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:32.912786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:31.739747Z","time spent":"1.172981819s","remote":"127.0.0.1:42818","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-03-02T18:30:32.960627Z","caller":"traceutil/trace.go:171","msg":"trace[1562175097] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.220532015s","start":"2026-03-02T18:30:31.740068Z","end":"2026-03-02T18:30:32.9606Z","steps":["trace[1562175097] 'process raft request' (duration: 1.21777038s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:32.960838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:31.740057Z","time spent":"1.22067187s","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:30:32.960953Z","caller":"traceutil/trace.go:171","msg":"trace[1626034910] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.220360651s","start":"2026-03-02T18:30:31.740566Z","end":"2026-03-02T18:30:32.960927Z","steps":["trace[1626034910] 'process raft request' (duration: 1.219992531s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:32.961182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:31.740559Z","time spent":"1.220535866s","remote":"127.0.0.1:42818","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-03-02T18:30:32.96151Z","caller":"traceutil/trace.go:171","msg":"trace[372839614] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.220104454s","start":"2026-03-02T18:30:31.741395Z","end":"2026-03-02T18:30:32.961499Z","steps":["trace[372839614] 'process raft request' (duration: 1.219423555s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:32.96159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:31.741382Z","time spent":"1.220181807s","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:30:32.961805Z","caller":"traceutil/trace.go:171","msg":"trace[1493375147] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:550; }","duration":"1.09087465s","start":"2026-03-02T18:30:31.870919Z","end":"2026-03-02T18:30:32.961794Z","steps":["trace[1493375147] 'read index received' (duration: 1.041625137s)","trace[1493375147] 'applied index is now lower than readState.Index' (duration: 49.248373ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:30:32.961883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090969393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:30:32.961924Z","caller":"traceutil/trace.go:171","msg":"trace[403395786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"1.091043384s","start":"2026-03-02T18:30:31.870872Z","end":"2026-03-02T18:30:32.961916Z","steps":["trace[403395786] 'agreement among raft nodes before linearized reading' (duration: 1.090983673s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:32.961959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:31.870854Z","time spent":"1.091098606s","remote":"127.0.0.1:42530","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-03-02T18:30:35.148227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.07098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:30:35.148242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.654185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:30:35.148326Z","caller":"traceutil/trace.go:171","msg":"trace[1256576702] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:573; }","duration":"206.182063ms","start":"2026-03-02T18:30:34.942111Z","end":"2026-03-02T18:30:35.148293Z","steps":["trace[1256576702] 'range keys from in-memory index tree' (duration: 206.027849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:35.148227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.560528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-02T18:30:35.148441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.669962ms","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-03-02T18:30:35.14845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.258797ms","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-03-02T18:30:35.148499Z","caller":"traceutil/trace.go:171","msg":"trace[190989405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"182.745744ms","start":"2026-03-02T18:30:34.965733Z","end":"2026-03-02T18:30:35.148479Z","steps":["trace[190989405] 'range keys from in-memory index tree' (duration: 182.544548ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:35.148545Z","caller":"traceutil/trace.go:171","msg":"trace[414608552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"231.39492ms","start":"2026-03-02T18:30:34.91713Z","end":"2026-03-02T18:30:35.148525Z","steps":["trace[414608552] 'range keys from in-memory index tree' (duration: 231.139864ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:35.148478Z","caller":"traceutil/trace.go:171","msg":"trace[1849651311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"182.863577ms","start":"2026-03-02T18:30:34.965602Z","end":"2026-03-02T18:30:35.148465Z","steps":["trace[1849651311] 'range keys from in-memory index tree' (duration: 182.447315ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:35.150075Z","caller":"traceutil/trace.go:171","msg":"trace[1512635242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"276.803429ms","start":"2026-03-02T18:30:34.871508Z","end":"2026-03-02T18:30:35.148311Z","steps":["trace[1512635242] 'range keys from in-memory index tree' (duration: 276.554712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:35.148254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.6173ms","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-03-02T18:30:35.150238Z","caller":"traceutil/trace.go:171","msg":"trace[1669371460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"184.610185ms","start":"2026-03-02T18:30:34.965601Z","end":"2026-03-02T18:30:35.150211Z","steps":["trace[1669371460] 'range keys from in-memory index tree' (duration: 182.536708ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:38.045699Z","caller":"traceutil/trace.go:171","msg":"trace[1326074618] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"103.109942ms","start":"2026-03-02T18:30:37.942563Z","end":"2026-03-02T18:30:38.045673Z","steps":["trace[1326074618] 'read index received' (duration: 101.799816ms)","trace[1326074618] 'applied index is now lower than readState.Index' (duration: 1.309036ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:30:38.045804Z","caller":"traceutil/trace.go:171","msg":"trace[1603531025] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"172.141645ms","start":"2026-03-02T18:30:37.87363Z","end":"2026-03-02T18:30:38.045772Z","steps":["trace[1603531025] 'process raft request' (duration: 171.056385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:38.046072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.427371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-03-02T18:30:38.046264Z","caller":"traceutil/trace.go:171","msg":"trace[1613145304] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:609; }","duration":"103.644177ms","start":"2026-03-02T18:30:37.942525Z","end":"2026-03-02T18:30:38.046169Z","steps":["trace[1613145304] 'agreement among raft nodes before linearized reading' (duration: 103.250356ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:45.049199Z","caller":"traceutil/trace.go:171","msg":"trace[249881237] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"180.534263ms","start":"2026-03-02T18:30:44.868644Z","end":"2026-03-02T18:30:45.049178Z","steps":["trace[249881237] 'read index received' (duration: 178.372804ms)","trace[249881237] 'applied index is now lower than readState.Index' (duration: 2.160279ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:30:45.049253Z","caller":"traceutil/trace.go:171","msg":"trace[1082960401] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"209.221544ms","start":"2026-03-02T18:30:44.840003Z","end":"2026-03-02T18:30:45.049225Z","steps":["trace[1082960401] 'process raft request' (duration: 206.956062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:45.049345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.681537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:30:45.049385Z","caller":"traceutil/trace.go:171","msg":"trace[1251823884] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"180.761248ms","start":"2026-03-02T18:30:44.868614Z","end":"2026-03-02T18:30:45.049376Z","steps":["trace[1251823884] 'agreement among raft nodes before linearized reading' (duration: 180.652085ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:30:51.172714Z","caller":"traceutil/trace.go:171","msg":"trace[1886295557] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"301.990916ms","start":"2026-03-02T18:30:50.870699Z","end":"2026-03-02T18:30:51.172689Z","steps":["trace[1886295557] 'read index received' (duration: 301.879623ms)","trace[1886295557] 'applied index is now lower than readState.Index' (duration: 110.243µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:30:51.17277Z","caller":"traceutil/trace.go:171","msg":"trace[730700876] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"536.266055ms","start":"2026-03-02T18:30:50.636471Z","end":"2026-03-02T18:30:51.172737Z","steps":["trace[730700876] 'process raft request' (duration: 536.052379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:51.172839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.119689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:30:51.172888Z","caller":"traceutil/trace.go:171","msg":"trace[1695454684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"302.211292ms","start":"2026-03-02T18:30:50.870666Z","end":"2026-03-02T18:30:51.172877Z","steps":["trace[1695454684] 'agreement among raft nodes before linearized reading' (duration: 302.13005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:51.172931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:50.87065Z","time spent":"302.273143ms","remote":"127.0.0.1:42530","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-03-02T18:30:51.172932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:50.63644Z","time spent":"536.405288ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:30:51.605961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.783864ms","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-03-02T18:30:51.60604Z","caller":"traceutil/trace.go:171","msg":"trace[1283907050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"362.908608ms","start":"2026-03-02T18:30:51.243114Z","end":"2026-03-02T18:30:51.606023Z","steps":["trace[1283907050] 'range keys from in-memory index tree' (duration: 362.62238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:51.606076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.613397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-02T18:30:51.606078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:51.243093Z","time spent":"362.975269ms","remote":"127.0.0.1:42818","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-03-02T18:30:51.606106Z","caller":"traceutil/trace.go:171","msg":"trace[36540201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:712; }","duration":"370.665799ms","start":"2026-03-02T18:30:51.235429Z","end":"2026-03-02T18:30:51.606094Z","steps":["trace[36540201] 'range keys from in-memory index tree' (duration: 370.529415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:51.606135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:51.235413Z","time spent":"370.710429ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T18:30:51.605961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.689035ms","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-03-02T18:30:51.6062Z","caller":"traceutil/trace.go:171","msg":"trace[517731711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"375.983653ms","start":"2026-03-02T18:30:51.230205Z","end":"2026-03-02T18:30:51.606189Z","steps":["trace[517731711] 'range keys from in-memory index tree' (duration: 375.587013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:51.606154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.511659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-02T18:30:51.606226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:51.230189Z","time spent":"376.029965ms","remote":"127.0.0.1:42818","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-03-02T18:30:51.606277Z","caller":"traceutil/trace.go:171","msg":"trace[613950694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:712; }","duration":"361.678864ms","start":"2026-03-02T18:30:51.244569Z","end":"2026-03-02T18:30:51.606248Z","steps":["trace[613950694] 'range keys from in-memory index tree' (duration: 361.312903ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:51.606335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:51.244559Z","time spent":"361.765206ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T18:30:52.095346Z","caller":"traceutil/trace.go:171","msg":"trace[5580818] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"483.70433ms","start":"2026-03-02T18:30:51.611603Z","end":"2026-03-02T18:30:52.095307Z","steps":["trace[5580818] 'process raft request' (duration: 483.631058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:52.095541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:51.611584Z","time spent":"483.853385ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:30:52.095531Z","caller":"traceutil/trace.go:171","msg":"trace[1543339127] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"483.258449ms","start":"2026-03-02T18:30:51.612242Z","end":"2026-03-02T18:30:52.095501Z","steps":["trace[1543339127] 'process raft request' (duration: 483.027972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:52.095704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:51.612235Z","time spent":"483.369001ms","remote":"127.0.0.1:42818","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-03-02T18:30:52.095904Z","caller":"traceutil/trace.go:171","msg":"trace[1236701321] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"484.993566ms","start":"2026-03-02T18:30:51.610877Z","end":"2026-03-02T18:30:52.09587Z","steps":["trace[1236701321] 'process raft request' (duration: 484.06612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:52.096122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:51.610856Z","time spent":"485.16328ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:30:56.371693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346059858154740,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T18:30:56.557632Z","caller":"traceutil/trace.go:171","msg":"trace[85746627] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"687.016825ms","start":"2026-03-02T18:30:55.870595Z","end":"2026-03-02T18:30:56.557612Z","steps":["trace[85746627] 'read index received' (duration: 686.8302ms)","trace[85746627] 'applied index is now lower than readState.Index' (duration: 185.545µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:30:56.55768Z","caller":"traceutil/trace.go:171","msg":"trace[1424606031] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"845.080549ms","start":"2026-03-02T18:30:55.712568Z","end":"2026-03-02T18:30:56.557648Z","steps":["trace[1424606031] 'process raft request' (duration: 844.915815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:56.557774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.167719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:30:56.557803Z","caller":"traceutil/trace.go:171","msg":"trace[5130305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"687.242961ms","start":"2026-03-02T18:30:55.87055Z","end":"2026-03-02T18:30:56.557793Z","steps":["trace[5130305] 'agreement among raft nodes before linearized reading' (duration: 687.18122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:56.557817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:55.712542Z","time spent":"845.204783ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:30:56.557827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:55.870531Z","time spent":"687.290763ms","remote":"127.0.0.1:42530","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-03-02T18:30:56.558057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.038379ms","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-03-02T18:30:56.558081Z","caller":"traceutil/trace.go:171","msg":"trace[1971786279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"451.104581ms","start":"2026-03-02T18:30:56.106968Z","end":"2026-03-02T18:30:56.558073Z","steps":["trace[1971786279] 'agreement among raft nodes before linearized reading' (duration: 450.990568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:56.558108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:56.10695Z","time spent":"451.146893ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T18:30:56.558235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.585087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-02T18:30:56.558265Z","caller":"traceutil/trace.go:171","msg":"trace[1990660505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"450.660579ms","start":"2026-03-02T18:30:56.107595Z","end":"2026-03-02T18:30:56.558256Z","steps":["trace[1990660505] 'agreement among raft nodes before linearized reading' (duration: 450.549916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:56.558292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:56.107575Z","time spent":"450.71007ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T18:30:56.558284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.707373ms","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-03-02T18:30:56.558352Z","caller":"traceutil/trace.go:171","msg":"trace[293581106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"449.800735ms","start":"2026-03-02T18:30:56.108534Z","end":"2026-03-02T18:30:56.558335Z","steps":["trace[293581106] 'agreement among raft nodes before linearized reading' (duration: 449.60914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:56.558381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.918015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:30:56.558421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:56.108525Z","time spent":"449.858677ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T18:30:56.558439Z","caller":"traceutil/trace.go:171","msg":"trace[1843810751] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:725; }","duration":"349.013828ms","start":"2026-03-02T18:30:56.209416Z","end":"2026-03-02T18:30:56.55843Z","steps":["trace[1843810751] 'agreement among raft nodes before linearized reading' (duration: 348.936936ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:56.558467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:56.209393Z","time spent":"349.06552ms","remote":"127.0.0.1:42692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-03-02T18:30:57.334841Z","caller":"traceutil/trace.go:171","msg":"trace[358507164] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"772.71575ms","start":"2026-03-02T18:30:56.562096Z","end":"2026-03-02T18:30:57.334812Z","steps":["trace[358507164] 'process raft request' (duration: 689.803691ms)","trace[358507164] 'compare' (duration: 82.520748ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:30:57.335002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:56.562083Z","time spent":"772.838803ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:30:57.33777Z","caller":"traceutil/trace.go:171","msg":"trace[196421767] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"775.620389ms","start":"2026-03-02T18:30:56.562123Z","end":"2026-03-02T18:30:57.337743Z","steps":["trace[196421767] 'process raft request' (duration: 775.465105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:57.337873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:56.562104Z","time spent":"775.711592ms","remote":"127.0.0.1:42818","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-03-02T18:30:57.33813Z","caller":"traceutil/trace.go:171","msg":"trace[427045707] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"775.373952ms","start":"2026-03-02T18:30:56.562746Z","end":"2026-03-02T18:30:57.33812Z","steps":["trace[427045707] 'process raft request' (duration: 774.957011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:57.338177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:56.61863Z","time spent":"719.543476ms","remote":"127.0.0.1:42620","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-02T18:30:57.338194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:56.562732Z","time spent":"775.431083ms","remote":"127.0.0.1:42818","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-03-02T18:30:57.561809Z","caller":"traceutil/trace.go:171","msg":"trace[1026941199] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"222.442775ms","start":"2026-03-02T18:30:57.339341Z","end":"2026-03-02T18:30:57.561783Z","steps":["trace[1026941199] 'process raft request' (duration: 190.532272ms)","trace[1026941199] 'compare' (duration: 31.775819ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:30:57.654245Z","caller":"traceutil/trace.go:171","msg":"trace[390729369] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"312.610052ms","start":"2026-03-02T18:30:57.341615Z","end":"2026-03-02T18:30:57.654226Z","steps":["trace[390729369] 'process raft request' (duration: 312.486239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:30:57.654381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:57.341598Z","time spent":"312.709556ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:30:59.130035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.207546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:30:59.130179Z","caller":"traceutil/trace.go:171","msg":"trace[2141345886] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"564.079441ms","start":"2026-03-02T18:30:58.566068Z","end":"2026-03-02T18:30:59.130147Z","steps":["trace[2141345886] 'process raft request' (duration: 185.658339ms)","trace[2141345886] 'compare' (duration: 377.844557ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:30:59.130264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:30:58.566049Z","time spent":"564.171964ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:15.299381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:14.719969Z","time spent":"579.396971ms","remote":"127.0.0.1:42562","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-02T18:31:15.299681Z","caller":"traceutil/trace.go:171","msg":"trace[740172861] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:807; }","duration":"427.552407ms","start":"2026-03-02T18:31:14.872107Z","end":"2026-03-02T18:31:15.299659Z","steps":["trace[740172861] 'read index received' (duration: 427.544916ms)","trace[740172861] 'applied index is now lower than readState.Index' (duration: 6.061µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:15.299826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.714441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:15.300098Z","caller":"traceutil/trace.go:171","msg":"trace[1687335012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"427.898356ms","start":"2026-03-02T18:31:14.87209Z","end":"2026-03-02T18:31:15.299989Z","steps":["trace[1687335012] 'agreement among raft nodes before linearized reading' (duration: 427.68693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:15.300149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:14.872024Z","time spent":"428.112862ms","remote":"127.0.0.1:42530","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-03-02T18:31:15.363633Z","caller":"traceutil/trace.go:171","msg":"trace[2032850880] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"250.072411ms","start":"2026-03-02T18:31:15.113524Z","end":"2026-03-02T18:31:15.363597Z","steps":["trace[2032850880] 'process raft request' (duration: 249.833424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:15.363778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.952386ms","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-03-02T18:31:15.363862Z","caller":"traceutil/trace.go:171","msg":"trace[1771390541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"164.088719ms","start":"2026-03-02T18:31:15.199751Z","end":"2026-03-02T18:31:15.36384Z","steps":["trace[1771390541] 'agreement among raft nodes before linearized reading' (duration: 163.845242ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:15.527219Z","caller":"traceutil/trace.go:171","msg":"trace[1982683529] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"130.645395ms","start":"2026-03-02T18:31:15.396534Z","end":"2026-03-02T18:31:15.527179Z","steps":["trace[1982683529] 'read index received' (duration: 49.643289ms)","trace[1982683529] 'applied index is now lower than readState.Index' (duration: 81.000356ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:15.527244Z","caller":"traceutil/trace.go:171","msg":"trace[1301275925] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"159.049202ms","start":"2026-03-02T18:31:15.368167Z","end":"2026-03-02T18:31:15.527216Z","steps":["trace[1301275925] 'process raft request' (duration: 78.018095ms)","trace[1301275925] 'compare' (duration: 80.747299ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:15.527393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.85071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-02T18:31:15.52747Z","caller":"traceutil/trace.go:171","msg":"trace[1343971746] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:783; }","duration":"130.951823ms","start":"2026-03-02T18:31:15.396493Z","end":"2026-03-02T18:31:15.527445Z","steps":["trace[1343971746] 'agreement among raft nodes before linearized reading' (duration: 130.84351ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:15.779892Z","caller":"traceutil/trace.go:171","msg":"trace[8431687] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"248.237421ms","start":"2026-03-02T18:31:15.531626Z","end":"2026-03-02T18:31:15.779863Z","steps":["trace[8431687] 'process raft request' (duration: 248.041546ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:15.78003Z","caller":"traceutil/trace.go:171","msg":"trace[2022235782] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"168.084438ms","start":"2026-03-02T18:31:15.611916Z","end":"2026-03-02T18:31:15.78Z","steps":["trace[2022235782] 'read index received' (duration: 167.668787ms)","trace[2022235782] 'applied index is now lower than readState.Index' (duration: 413.341µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:15.780228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.275454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T18:31:15.780269Z","caller":"traceutil/trace.go:171","msg":"trace[62358751] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:786; }","duration":"168.386317ms","start":"2026-03-02T18:31:15.611872Z","end":"2026-03-02T18:31:15.780258Z","steps":["trace[62358751] 'agreement among raft nodes before linearized reading' (duration: 168.273154ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:15.780249Z","caller":"traceutil/trace.go:171","msg":"trace[1664263193] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"247.753458ms","start":"2026-03-02T18:31:15.532291Z","end":"2026-03-02T18:31:15.780045Z","steps":["trace[1664263193] 'process raft request' (duration: 247.522042ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:15.934144Z","caller":"traceutil/trace.go:171","msg":"trace[1328018103] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"150.132867ms","start":"2026-03-02T18:31:15.783979Z","end":"2026-03-02T18:31:15.934112Z","steps":["trace[1328018103] 'process raft request' (duration: 52.228319ms)","trace[1328018103] 'compare' (duration: 97.778804ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:18.046549Z","caller":"traceutil/trace.go:171","msg":"trace[1280062050] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"257.737481ms","start":"2026-03-02T18:31:17.788791Z","end":"2026-03-02T18:31:18.046529Z","steps":["trace[1280062050] 'process raft request' (duration: 257.531265ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:18.41365Z","caller":"traceutil/trace.go:171","msg":"trace[597228503] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:816; }","duration":"541.468802ms","start":"2026-03-02T18:31:17.872152Z","end":"2026-03-02T18:31:18.413621Z","steps":["trace[597228503] 'read index received' (duration: 174.135994ms)","trace[597228503] 'applied index is now lower than readState.Index' (duration: 367.331108ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:18.413806Z","caller":"traceutil/trace.go:171","msg":"trace[851107195] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"624.577056ms","start":"2026-03-02T18:31:17.789184Z","end":"2026-03-02T18:31:18.413761Z","steps":["trace[851107195] 'process raft request' (duration: 582.044503ms)","trace[851107195] 'compare' (duration: 42.141403ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:18.414008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:17.789172Z","time spent":"624.71591ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:18.414042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.703879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:18.414099Z","caller":"traceutil/trace.go:171","msg":"trace[932214229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"541.981056ms","start":"2026-03-02T18:31:17.8721Z","end":"2026-03-02T18:31:18.414081Z","steps":["trace[932214229] 'agreement among raft nodes before linearized reading' (duration: 541.676518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:18.414143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:17.872085Z","time spent":"542.044858ms","remote":"127.0.0.1:42530","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-03-02T18:31:18.414165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.539926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-02T18:31:18.414214Z","caller":"traceutil/trace.go:171","msg":"trace[833517137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"476.631628ms","start":"2026-03-02T18:31:17.937568Z","end":"2026-03-02T18:31:18.4142Z","steps":["trace[833517137] 'agreement among raft nodes before linearized reading' (duration: 476.389702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:18.41427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:17.937548Z","time spent":"476.713561ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-02T18:31:30.129136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11712346059858155099,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-02T18:31:30.201812Z","caller":"traceutil/trace.go:171","msg":"trace[1126648884] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"573.676165ms","start":"2026-03-02T18:31:29.628107Z","end":"2026-03-02T18:31:30.201783Z","steps":["trace[1126648884] 'read index received' (duration: 573.4974ms)","trace[1126648884] 'applied index is now lower than readState.Index' (duration: 177.235µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:30.20216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.020294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T18:31:30.202257Z","caller":"traceutil/trace.go:171","msg":"trace[614337124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:872; }","duration":"574.196559ms","start":"2026-03-02T18:31:29.628041Z","end":"2026-03-02T18:31:30.202238Z","steps":["trace[614337124] 'agreement among raft nodes before linearized reading' (duration: 573.907021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:30.2023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:29.62801Z","time spent":"574.281451ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T18:31:30.202312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.633318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:31:30.202359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.449206ms","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-03-02T18:31:30.202426Z","caller":"traceutil/trace.go:171","msg":"trace[1534436422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:872; }","duration":"329.789153ms","start":"2026-03-02T18:31:29.872576Z","end":"2026-03-02T18:31:30.202365Z","steps":["trace[1534436422] 'agreement among raft nodes before linearized reading' (duration: 329.645959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:30.202489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:29.872547Z","time spent":"329.929126ms","remote":"127.0.0.1:42530","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-03-02T18:31:30.202488Z","caller":"traceutil/trace.go:171","msg":"trace[1967739759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:872; }","duration":"108.625101ms","start":"2026-03-02T18:31:30.093836Z","end":"2026-03-02T18:31:30.202461Z","steps":["trace[1967739759] 'agreement among raft nodes before linearized reading' (duration: 108.301512ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:30.202666Z","caller":"traceutil/trace.go:171","msg":"trace[1072933625] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"595.644775ms","start":"2026-03-02T18:31:29.606273Z","end":"2026-03-02T18:31:30.201918Z","steps":["trace[1072933625] 'process raft request' (duration: 595.291806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:30.203383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:29.606253Z","time spent":"597.055414ms","remote":"127.0.0.1:42818","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-03-02T18:31:30.208155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.476826ms","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-03-02T18:31:30.208215Z","caller":"traceutil/trace.go:171","msg":"trace[681121713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:872; }","duration":"548.564777ms","start":"2026-03-02T18:31:29.659632Z","end":"2026-03-02T18:31:30.208197Z","steps":["trace[681121713] 'agreement among raft nodes before linearized reading' (duration: 542.349527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:30.208251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:29.659621Z","time spent":"548.621139ms","remote":"127.0.0.1:42818","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-03-02T18:31:32.921928Z","caller":"traceutil/trace.go:171","msg":"trace[1348453158] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"699.846726ms","start":"2026-03-02T18:31:32.222058Z","end":"2026-03-02T18:31:32.921904Z","steps":["trace[1348453158] 'process raft request' (duration: 699.712442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:32.922075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:32.222042Z","time spent":"699.957949ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:31:32.922569Z","caller":"traceutil/trace.go:171","msg":"trace[1379401101] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"698.623782ms","start":"2026-03-02T18:31:32.223924Z","end":"2026-03-02T18:31:32.922548Z","steps":["trace[1379401101] 'process raft request' (duration: 698.438647ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:32.922591Z","caller":"traceutil/trace.go:171","msg":"trace[547840629] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"697.738579ms","start":"2026-03-02T18:31:32.224826Z","end":"2026-03-02T18:31:32.922564Z","steps":["trace[547840629] 'process raft request' (duration: 697.665467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:32.922676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:32.223908Z","time spent":"698.715995ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:32.922743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.562558ms","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-03-02T18:31:32.922789Z","caller":"traceutil/trace.go:171","msg":"trace[1127240468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"442.66354ms","start":"2026-03-02T18:31:32.480109Z","end":"2026-03-02T18:31:32.922773Z","steps":["trace[1127240468] 'agreement among raft nodes before linearized reading' (duration: 442.496806ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:32.922551Z","caller":"traceutil/trace.go:171","msg":"trace[257113286] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:909; }","duration":"442.359362ms","start":"2026-03-02T18:31:32.48017Z","end":"2026-03-02T18:31:32.922529Z","steps":["trace[257113286] 'read index received' (duration: 441.790366ms)","trace[257113286] 'applied index is now lower than readState.Index' (duration: 568.076µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:32.922823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:32.480087Z","time spent":"442.728422ms","remote":"127.0.0.1:42818","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-03-02T18:31:32.922907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:32.224812Z","time spent":"697.893403ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:32.922998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.932378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-02T18:31:32.923061Z","caller":"traceutil/trace.go:171","msg":"trace[127116058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"390.040081ms","start":"2026-03-02T18:31:32.533002Z","end":"2026-03-02T18:31:32.923042Z","steps":["trace[127116058] 'agreement among raft nodes before linearized reading' (duration: 389.871616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:32.923118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:32.532981Z","time spent":"390.128093ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-02T18:31:49.135734Z","caller":"traceutil/trace.go:171","msg":"trace[637551548] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"110.665747ms","start":"2026-03-02T18:31:49.025044Z","end":"2026-03-02T18:31:49.13571Z","steps":["trace[637551548] 'process raft request' (duration: 110.483552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:49.343172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.970208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:31:49.343287Z","caller":"traceutil/trace.go:171","msg":"trace[133121820] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:972; }","duration":"302.192486ms","start":"2026-03-02T18:31:49.041077Z","end":"2026-03-02T18:31:49.343269Z","steps":["trace[133121820] 'read index received' (duration: 94.450314ms)","trace[133121820] 'applied index is now lower than readState.Index' (duration: 207.741122ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:49.343364Z","caller":"traceutil/trace.go:171","msg":"trace[1105270525] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"312.647762ms","start":"2026-03-02T18:31:49.030705Z","end":"2026-03-02T18:31:49.343353Z","steps":["trace[1105270525] 'process raft request' (duration: 210.418446ms)","trace[1105270525] 'compare' (duration: 101.839215ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:49.34342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.030678Z","time spent":"312.710804ms","remote":"127.0.0.1:42718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:49.343603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.523485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-02T18:31:49.343632Z","caller":"traceutil/trace.go:171","msg":"trace[1114124820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"302.576166ms","start":"2026-03-02T18:31:49.041045Z","end":"2026-03-02T18:31:49.343621Z","steps":["trace[1114124820] 'agreement among raft nodes before linearized reading' (duration: 302.471833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:49.343661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.041033Z","time spent":"302.621868ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-02T18:31:49.343772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.664988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-02T18:31:49.343773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.575977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-02T18:31:49.343852Z","caller":"traceutil/trace.go:171","msg":"trace[1669854424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"302.773962ms","start":"2026-03-02T18:31:49.041059Z","end":"2026-03-02T18:31:49.343833Z","steps":["trace[1669854424] 'agreement among raft nodes before linearized reading' (duration: 302.532025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:49.343892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.041047Z","time spent":"302.836373ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-02T18:31:49.343875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.203592ms","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-03-02T18:31:49.343883Z","caller":"traceutil/trace.go:171","msg":"trace[1990910688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"298.68879ms","start":"2026-03-02T18:31:49.045159Z","end":"2026-03-02T18:31:49.343847Z","steps":["trace[1990910688] 'agreement among raft nodes before linearized reading' (duration: 298.454464ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:49.343958Z","caller":"traceutil/trace.go:171","msg":"trace[111538034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:940; }","duration":"300.328715ms","start":"2026-03-02T18:31:49.043607Z","end":"2026-03-02T18:31:49.343936Z","steps":["trace[111538034] 'agreement among raft nodes before linearized reading' (duration: 300.072688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:49.344004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.043594Z","time spent":"300.401377ms","remote":"127.0.0.1:42818","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-03-02T18:31:49.696164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.73595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:31:49.69642Z","caller":"traceutil/trace.go:171","msg":"trace[14488474] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"347.935249ms","start":"2026-03-02T18:31:49.348447Z","end":"2026-03-02T18:31:49.696382Z","steps":["trace[14488474] 'process raft request' (duration: 134.839609ms)","trace[14488474] 'compare' (duration: 212.587616ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:49.696517Z","caller":"traceutil/trace.go:171","msg":"trace[326913893] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"346.119149ms","start":"2026-03-02T18:31:49.35037Z","end":"2026-03-02T18:31:49.696489Z","steps":["trace[326913893] 'process raft request' (duration: 345.993315ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:49.696516Z","caller":"traceutil/trace.go:171","msg":"trace[881755071] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"346.675074ms","start":"2026-03-02T18:31:49.349789Z","end":"2026-03-02T18:31:49.696464Z","steps":["trace[881755071] 'process raft request' (duration: 346.508899ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:49.696552Z","caller":"traceutil/trace.go:171","msg":"trace[1054522443] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"341.132693ms","start":"2026-03-02T18:31:49.355399Z","end":"2026-03-02T18:31:49.696531Z","steps":["trace[1054522443] 'process raft request' (duration: 341.089282ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:49.696599Z","caller":"traceutil/trace.go:171","msg":"trace[827859346] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"341.731289ms","start":"2026-03-02T18:31:49.35485Z","end":"2026-03-02T18:31:49.696581Z","steps":["trace[827859346] 'process raft request' (duration: 341.588845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:49.696625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.355389Z","time spent":"341.206285ms","remote":"127.0.0.1:43022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:49.69664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.350363Z","time spent":"346.214482ms","remote":"127.0.0.1:42818","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-03-02T18:31:49.696641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.348425Z","time spent":"348.086533ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:49.696669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.35484Z","time spent":"341.790511ms","remote":"127.0.0.1:42818","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-03-02T18:31:49.696719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.349768Z","time spent":"346.87669ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:31:49.696761Z","caller":"traceutil/trace.go:171","msg":"trace[908311389] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"341.554214ms","start":"2026-03-02T18:31:49.355199Z","end":"2026-03-02T18:31:49.696754Z","steps":["trace[908311389] 'process raft request' (duration: 341.264176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:49.696794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.355188Z","time spent":"341.587705ms","remote":"127.0.0.1:42818","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-03-02T18:31:49.696803Z","caller":"traceutil/trace.go:171","msg":"trace[1986563859] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"346.334735ms","start":"2026-03-02T18:31:49.350456Z","end":"2026-03-02T18:31:49.69679Z","steps":["trace[1986563859] 'process raft request' (duration: 345.941484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:49.696872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.350436Z","time spent":"346.401597ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:51.046167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071301598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:51.046246Z","caller":"traceutil/trace.go:171","msg":"trace[1405814516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:947; }","duration":"1.07138754s","start":"2026-03-02T18:31:49.974839Z","end":"2026-03-02T18:31:51.046227Z","steps":["trace[1405814516] 'range keys from in-memory index tree' (duration: 1.071244207s)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:51.046284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:49.974801Z","time spent":"1.071475143s","remote":"127.0.0.1:42530","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-03-02T18:31:51.558781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281774517s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-02T18:31:51.558994Z","caller":"traceutil/trace.go:171","msg":"trace[1736235797] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"917.81418ms","start":"2026-03-02T18:31:50.641148Z","end":"2026-03-02T18:31:51.558962Z","steps":["trace[1736235797] 'read index received' (duration: 102.713µs)","trace[1736235797] 'applied index is now lower than readState.Index' (duration: 917.710437ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:51.559249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.093457ms","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-03-02T18:31:51.559294Z","caller":"traceutil/trace.go:171","msg":"trace[9870014] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:947; }","duration":"918.179959ms","start":"2026-03-02T18:31:50.6411Z","end":"2026-03-02T18:31:51.55928Z","steps":["trace[9870014] 'agreement among raft nodes before linearized reading' (duration: 918.101447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:51.55933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:50.641081Z","time spent":"918.239891ms","remote":"127.0.0.1:42584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-03-02T18:31:51.559614Z","caller":"traceutil/trace.go:171","msg":"trace[904519580] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"587.354359ms","start":"2026-03-02T18:31:50.97224Z","end":"2026-03-02T18:31:51.559594Z","steps":["trace[904519580] 'process raft request' (duration: 586.638649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:51.559753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:50.972198Z","time spent":"587.496213ms","remote":"127.0.0.1:42818","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-03-02T18:31:51.705942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.616028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:51.706027Z","caller":"traceutil/trace.go:171","msg":"trace[263896671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"658.727351ms","start":"2026-03-02T18:31:51.047276Z","end":"2026-03-02T18:31:51.706003Z","steps":["trace[263896671] 'agreement among raft nodes before linearized reading' (duration: 512.284565ms)","trace[263896671] 'range keys from in-memory index tree' (duration: 146.312232ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:51.70609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.047262Z","time spent":"658.813683ms","remote":"127.0.0.1:42536","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-03-02T18:31:51.706214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.950775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:51.706267Z","caller":"traceutil/trace.go:171","msg":"trace[370501150] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:948; }","duration":"486.013456ms","start":"2026-03-02T18:31:51.220241Z","end":"2026-03-02T18:31:51.706254Z","steps":["trace[370501150] 'agreement among raft nodes before linearized reading' (duration: 339.604311ms)","trace[370501150] 'count revisions from in-memory index tree' (duration: 146.332463ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:51.706287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.220203Z","time spent":"486.079128ms","remote":"127.0.0.1:42830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-03-02T18:31:51.707287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.184511ms","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-03-02T18:31:51.707348Z","caller":"traceutil/trace.go:171","msg":"trace[1497131588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:948; }","duration":"486.272514ms","start":"2026-03-02T18:31:51.221068Z","end":"2026-03-02T18:31:51.70734Z","steps":["trace[1497131588] 'agreement among raft nodes before linearized reading' (duration: 338.790669ms)","trace[1497131588] 'range keys from in-memory index tree' (duration: 147.385272ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:51.707366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.221053Z","time spent":"486.308405ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-02T18:31:51.845283Z","caller":"traceutil/trace.go:171","msg":"trace[415610842] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"134.925671ms","start":"2026-03-02T18:31:51.710333Z","end":"2026-03-02T18:31:51.845259Z","steps":["trace[415610842] 'process raft request' (duration: 134.744086ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:52.277294Z","caller":"traceutil/trace.go:171","msg":"trace[874585702] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:983; }","duration":"561.331676ms","start":"2026-03-02T18:31:51.715941Z","end":"2026-03-02T18:31:52.277273Z","steps":["trace[874585702] 'read index received' (duration: 129.250036ms)","trace[874585702] 'applied index is now lower than readState.Index' (duration: 432.08072ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:52.278038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.076766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:31:52.278054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.061326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:52.278134Z","caller":"traceutil/trace.go:171","msg":"trace[1559124761] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:954; }","duration":"524.20259ms","start":"2026-03-02T18:31:51.753914Z","end":"2026-03-02T18:31:52.278117Z","steps":["trace[1559124761] 'agreement among raft nodes before linearized reading' (duration: 523.802609ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:52.278143Z","caller":"traceutil/trace.go:171","msg":"trace[1173777989] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"567.230117ms","start":"2026-03-02T18:31:51.710884Z","end":"2026-03-02T18:31:52.278114Z","steps":["trace[1173777989] 'process raft request' (duration: 566.23831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:52.278187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.753894Z","time spent":"524.282542ms","remote":"127.0.0.1:42834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-03-02T18:31:52.278272Z","caller":"traceutil/trace.go:171","msg":"trace[1440298700] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"566.734144ms","start":"2026-03-02T18:31:51.711512Z","end":"2026-03-02T18:31:52.278246Z","steps":["trace[1440298700] 'process raft request' (duration: 565.659394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:52.278299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.710874Z","time spent":"567.34411ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:31:52.278088Z","caller":"traceutil/trace.go:171","msg":"trace[1616171146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"562.171159ms","start":"2026-03-02T18:31:51.715906Z","end":"2026-03-02T18:31:52.278077Z","steps":["trace[1616171146] 'agreement among raft nodes before linearized reading' (duration: 561.682866ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:52.278442Z","caller":"traceutil/trace.go:171","msg":"trace[1860174399] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"567.632229ms","start":"2026-03-02T18:31:51.710797Z","end":"2026-03-02T18:31:52.278429Z","steps":["trace[1860174399] 'process raft request' (duration: 565.572012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:52.278452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.715898Z","time spent":"562.542959ms","remote":"127.0.0.1:42530","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-03-02T18:31:52.278448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.711499Z","time spent":"566.840056ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:31:52.278036Z","caller":"traceutil/trace.go:171","msg":"trace[1403247525] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"498.167837ms","start":"2026-03-02T18:31:51.779847Z","end":"2026-03-02T18:31:52.278014Z","steps":["trace[1403247525] 'process raft request' (duration: 497.388206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:52.278562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.710778Z","time spent":"567.69943ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:52.278633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.779795Z","time spent":"498.801835ms","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T18:31:52.278571Z","caller":"traceutil/trace.go:171","msg":"trace[1743456021] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"565.390477ms","start":"2026-03-02T18:31:51.712922Z","end":"2026-03-02T18:31:52.278313Z","steps":["trace[1743456021] 'process raft request' (duration: 564.282616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:52.278941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:51.712907Z","time spent":"565.977693ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:52.793243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.472189ms","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-03-02T18:31:52.793497Z","caller":"traceutil/trace.go:171","msg":"trace[2110363142] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:954; }","duration":"263.602332ms","start":"2026-03-02T18:31:52.529693Z","end":"2026-03-02T18:31:52.793295Z","steps":["trace[2110363142] 'count revisions from in-memory index tree' (duration: 263.349385ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:54.316247Z","caller":"traceutil/trace.go:171","msg":"trace[1138922309] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"446.798554ms","start":"2026-03-02T18:31:53.869429Z","end":"2026-03-02T18:31:54.316227Z","steps":["trace[1138922309] 'read index received' (duration: 446.643369ms)","trace[1138922309] 'applied index is now lower than readState.Index' (duration: 154.335µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:54.316353Z","caller":"traceutil/trace.go:171","msg":"trace[1130341387] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"463.238284ms","start":"2026-03-02T18:31:53.853093Z","end":"2026-03-02T18:31:54.316331Z","steps":["trace[1130341387] 'process raft request' (duration: 462.996727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:54.316429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.972638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:31:54.316491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:53.853072Z","time spent":"463.335056ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:31:54.316503Z","caller":"traceutil/trace.go:171","msg":"trace[1513924153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"447.106922ms","start":"2026-03-02T18:31:53.869377Z","end":"2026-03-02T18:31:54.316484Z","steps":["trace[1513924153] 'agreement among raft nodes before linearized reading' (duration: 446.968528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:54.316549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:53.869358Z","time spent":"447.182504ms","remote":"127.0.0.1:42536","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-03-02T18:31:54.951881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.27667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:31:54.951978Z","caller":"traceutil/trace.go:171","msg":"trace[2071977888] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"630.433497ms","start":"2026-03-02T18:31:54.321526Z","end":"2026-03-02T18:31:54.95196Z","steps":["trace[2071977888] 'process raft request' (duration: 495.005172ms)","trace[2071977888] 'compare' (duration: 135.177398ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:54.952152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:54.321505Z","time spent":"630.508239ms","remote":"127.0.0.1:42818","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-03-02T18:31:55.211541Z","caller":"traceutil/trace.go:171","msg":"trace[370534472] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"888.684903ms","start":"2026-03-02T18:31:54.322833Z","end":"2026-03-02T18:31:55.211518Z","steps":["trace[370534472] 'process raft request' (duration: 829.028941ms)","trace[370534472] 'compare' (duration: 59.481767ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:55.211691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:54.322814Z","time spent":"888.799415ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:31:55.211877Z","caller":"traceutil/trace.go:171","msg":"trace[549003861] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"887.087529ms","start":"2026-03-02T18:31:54.324754Z","end":"2026-03-02T18:31:55.211841Z","steps":["trace[549003861] 'process raft request' (duration: 886.711908ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:55.211983Z","caller":"traceutil/trace.go:171","msg":"trace[80124325] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:990; }","duration":"491.25066ms","start":"2026-03-02T18:31:54.720708Z","end":"2026-03-02T18:31:55.211959Z","steps":["trace[80124325] 'read index received' (duration: 95.840422ms)","trace[80124325] 'applied index is now lower than readState.Index' (duration: 395.409258ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:55.212083Z","caller":"traceutil/trace.go:171","msg":"trace[497047545] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"887.238663ms","start":"2026-03-02T18:31:54.324827Z","end":"2026-03-02T18:31:55.212066Z","steps":["trace[497047545] 'process raft request' (duration: 886.975265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:55.212106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:54.324749Z","time spent":"887.258593ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:55.212156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:54.324811Z","time spent":"887.311705ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:55.212321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.593569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-02T18:31:55.212375Z","caller":"traceutil/trace.go:171","msg":"trace[745579323] range","detail":"{range_begin:/registry/masterleases/199.204.45.67; range_end:; response_count:1; response_revision:959; }","duration":"491.703632ms","start":"2026-03-02T18:31:54.72066Z","end":"2026-03-02T18:31:55.212363Z","steps":["trace[745579323] 'agreement among raft nodes before linearized reading' (duration: 491.417724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:55.212412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:54.72064Z","time spent":"491.763374ms","remote":"127.0.0.1:42562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.67\" "} {"level":"warn","ts":"2026-03-02T18:31:55.304422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.033049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:31:55.304451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.344861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T18:31:55.304517Z","caller":"traceutil/trace.go:171","msg":"trace[1182966198] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:960; }","duration":"345.169533ms","start":"2026-03-02T18:31:54.959326Z","end":"2026-03-02T18:31:55.304495Z","steps":["trace[1182966198] 'agreement among raft nodes before linearized reading' (duration: 345.006059ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:55.304534Z","caller":"traceutil/trace.go:171","msg":"trace[647397535] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:960; }","duration":"514.475205ms","start":"2026-03-02T18:31:54.790041Z","end":"2026-03-02T18:31:55.304516Z","steps":["trace[647397535] 'agreement among raft nodes before linearized reading' (duration: 514.268019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:55.304577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:54.79002Z","time spent":"514.547146ms","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":120,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-02T18:31:55.30444Z","caller":"traceutil/trace.go:171","msg":"trace[549009397] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"472.99225ms","start":"2026-03-02T18:31:54.83142Z","end":"2026-03-02T18:31:55.304412Z","steps":["trace[549009397] 'process raft request' (duration: 472.763374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:55.304634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:54.959311Z","time spent":"345.248875ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-02T18:31:55.304448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.744757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T18:31:55.304751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:54.831402Z","time spent":"473.275177ms","remote":"127.0.0.1:42620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T18:31:55.304778Z","caller":"traceutil/trace.go:171","msg":"trace[1352221948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"430.081947ms","start":"2026-03-02T18:31:54.874643Z","end":"2026-03-02T18:31:55.304725Z","steps":["trace[1352221948] 'agreement among raft nodes before linearized reading' (duration: 429.709006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:55.304859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:54.874628Z","time spent":"430.180679ms","remote":"127.0.0.1:42530","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-03-02T18:31:55.497977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.388605ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-02T18:31:55.498184Z","caller":"traceutil/trace.go:171","msg":"trace[1005884158] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"187.668624ms","start":"2026-03-02T18:31:55.310499Z","end":"2026-03-02T18:31:55.498168Z","steps":["trace[1005884158] 'process raft request' (duration: 187.580951ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:55.498331Z","caller":"traceutil/trace.go:171","msg":"trace[855085866] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"191.775956ms","start":"2026-03-02T18:31:55.306527Z","end":"2026-03-02T18:31:55.498303Z","steps":["trace[855085866] 'read index received' (duration: 29.927099ms)","trace[855085866] 'applied index is now lower than readState.Index' (duration: 161.842257ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:55.498538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.983022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:55.498592Z","caller":"traceutil/trace.go:171","msg":"trace[875710050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"192.086635ms","start":"2026-03-02T18:31:55.306492Z","end":"2026-03-02T18:31:55.498579Z","steps":["trace[875710050] 'agreement among raft nodes before linearized reading' (duration: 191.92891ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:55.589986Z","caller":"traceutil/trace.go:171","msg":"trace[1889572858] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"247.207233ms","start":"2026-03-02T18:31:55.342757Z","end":"2026-03-02T18:31:55.589964Z","steps":["trace[1889572858] 'process raft request' (duration: 247.08266ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:55.781563Z","caller":"traceutil/trace.go:171","msg":"trace[353504159] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:997; }","duration":"279.769903ms","start":"2026-03-02T18:31:55.501778Z","end":"2026-03-02T18:31:55.781548Z","steps":["trace[353504159] 'read index received' (duration: 88.064179ms)","trace[353504159] 'applied index is now lower than readState.Index' (duration: 191.705024ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:55.781639Z","caller":"traceutil/trace.go:171","msg":"trace[1796864116] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"277.891582ms","start":"2026-03-02T18:31:55.503742Z","end":"2026-03-02T18:31:55.781634Z","steps":["trace[1796864116] 'process raft request' (duration: 277.778849ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:55.781659Z","caller":"traceutil/trace.go:171","msg":"trace[694607511] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"282.086267ms","start":"2026-03-02T18:31:55.499538Z","end":"2026-03-02T18:31:55.781625Z","steps":["trace[694607511] 'process raft request' (duration: 281.897772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:55.781874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.070972ms","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-03-02T18:31:55.781945Z","caller":"traceutil/trace.go:171","msg":"trace[1306420700] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:964; }","duration":"280.186084ms","start":"2026-03-02T18:31:55.501742Z","end":"2026-03-02T18:31:55.781928Z","steps":["trace[1306420700] 'agreement among raft nodes before linearized reading' (duration: 279.916047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:56.416312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.381383ms","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-03-02T18:31:56.416427Z","caller":"traceutil/trace.go:171","msg":"trace[1557194955] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:964; }","duration":"491.506086ms","start":"2026-03-02T18:31:55.924905Z","end":"2026-03-02T18:31:56.416411Z","steps":["trace[1557194955] 'range keys from in-memory index tree' (duration: 491.369122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:56.416579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.584289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:31:56.416825Z","caller":"traceutil/trace.go:171","msg":"trace[773475526] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"631.041504ms","start":"2026-03-02T18:31:55.785764Z","end":"2026-03-02T18:31:56.416806Z","steps":["trace[773475526] 'process raft request' (duration: 630.928521ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:56.416807Z","caller":"traceutil/trace.go:171","msg":"trace[969642660] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"823.053236ms","start":"2026-03-02T18:31:55.59372Z","end":"2026-03-02T18:31:56.416774Z","steps":["trace[969642660] 'process raft request' (duration: 297.134549ms)","trace[969642660] 'compare' (duration: 525.347191ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:56.416843Z","caller":"traceutil/trace.go:171","msg":"trace[973331531] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"635.211487ms","start":"2026-03-02T18:31:55.781613Z","end":"2026-03-02T18:31:56.416825Z","steps":["trace[973331531] 'read index received' (duration: 109.249059ms)","trace[973331531] 'applied index is now lower than readState.Index' (duration: 525.960208ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:56.416922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:55.785749Z","time spent":"631.122066ms","remote":"127.0.0.1:42818","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-03-02T18:31:56.416972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:55.59358Z","time spent":"823.308943ms","remote":"127.0.0.1:42620","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-03-02T18:31:56.417001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.641105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:56.41705Z","caller":"traceutil/trace.go:171","msg":"trace[1096800921] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:966; }","duration":"758.730317ms","start":"2026-03-02T18:31:55.658303Z","end":"2026-03-02T18:31:56.417033Z","steps":["trace[1096800921] 'agreement among raft nodes before linearized reading' (duration: 758.611324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:56.417113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:55.658282Z","time spent":"758.809989ms","remote":"127.0.0.1:46988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-02T18:31:56.442177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.543996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-03-02T18:31:56.442245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.037394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:56.442254Z","caller":"traceutil/trace.go:171","msg":"trace[627668536] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:967; }","duration":"658.66232ms","start":"2026-03-02T18:31:55.783573Z","end":"2026-03-02T18:31:56.442236Z","steps":["trace[627668536] 'agreement among raft nodes before linearized reading' (duration: 658.486095ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:56.442287Z","caller":"traceutil/trace.go:171","msg":"trace[1765200213] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:967; }","duration":"230.095435ms","start":"2026-03-02T18:31:56.212175Z","end":"2026-03-02T18:31:56.44227Z","steps":["trace[1765200213] 'agreement among raft nodes before linearized reading' (duration: 230.048423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:56.442281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.122116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-02T18:31:56.442297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:55.783554Z","time spent":"658.733641ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-03-02T18:31:56.442341Z","caller":"traceutil/trace.go:171","msg":"trace[744333227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"120.231489ms","start":"2026-03-02T18:31:56.322093Z","end":"2026-03-02T18:31:56.442325Z","steps":["trace[744333227] 'agreement among raft nodes before linearized reading' (duration: 119.985242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:56.442445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.886922ms","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-03-02T18:31:56.442509Z","caller":"traceutil/trace.go:171","msg":"trace[2069929270] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:967; }","duration":"562.037326ms","start":"2026-03-02T18:31:55.880457Z","end":"2026-03-02T18:31:56.442495Z","steps":["trace[2069929270] 'agreement among raft nodes before linearized reading' (duration: 561.894662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:56.442186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.663749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:56.44253Z","caller":"traceutil/trace.go:171","msg":"trace[2029254607] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"330.43209ms","start":"2026-03-02T18:31:56.11208Z","end":"2026-03-02T18:31:56.442512Z","steps":["trace[2029254607] 'process raft request' (duration: 329.856404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:56.44256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:55.88044Z","time spent":"562.106197ms","remote":"127.0.0.1:42826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-02T18:31:56.442584Z","caller":"traceutil/trace.go:171","msg":"trace[1370321596] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:967; }","duration":"470.07744ms","start":"2026-03-02T18:31:55.972495Z","end":"2026-03-02T18:31:56.442572Z","steps":["trace[1370321596] 'agreement among raft nodes before linearized reading' (duration: 469.605797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:56.442626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:55.972446Z","time spent":"470.165572ms","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-03-02T18:31:56.442657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:56.112055Z","time spent":"330.529272ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:31:57.347314Z","caller":"traceutil/trace.go:171","msg":"trace[203727402] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"899.876178ms","start":"2026-03-02T18:31:56.447419Z","end":"2026-03-02T18:31:57.347295Z","steps":["trace[203727402] 'process raft request' (duration: 889.819303ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:57.347299Z","caller":"traceutil/trace.go:171","msg":"trace[1883298176] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"474.874941ms","start":"2026-03-02T18:31:56.872395Z","end":"2026-03-02T18:31:57.34727Z","steps":["trace[1883298176] 'read index received' (duration: 464.822076ms)","trace[1883298176] 'applied index is now lower than readState.Index' (duration: 10.051605ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:57.347464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:56.447406Z","time spent":"899.97065ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:31:57.347557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.159809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:57.347605Z","caller":"traceutil/trace.go:171","msg":"trace[2092512389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"475.247672ms","start":"2026-03-02T18:31:56.872342Z","end":"2026-03-02T18:31:57.34759Z","steps":["trace[2092512389] 'agreement among raft nodes before linearized reading' (duration: 475.16868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:57.347645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:56.872321Z","time spent":"475.314103ms","remote":"127.0.0.1:42530","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-03-02T18:31:57.348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.575285ms","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-03-02T18:31:57.347998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.073367ms","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-03-02T18:31:57.348037Z","caller":"traceutil/trace.go:171","msg":"trace[1157465773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"129.667118ms","start":"2026-03-02T18:31:57.218357Z","end":"2026-03-02T18:31:57.348024Z","steps":["trace[1157465773] 'agreement among raft nodes before linearized reading' (duration: 129.559085ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:31:57.348049Z","caller":"traceutil/trace.go:171","msg":"trace[613142496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"392.181239ms","start":"2026-03-02T18:31:56.955854Z","end":"2026-03-02T18:31:57.348036Z","steps":["trace[613142496] 'agreement among raft nodes before linearized reading' (duration: 392.029445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:57.348064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.135938ms","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-03-02T18:31:57.348099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:56.955834Z","time spent":"392.248421ms","remote":"127.0.0.1:42818","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-03-02T18:31:57.348122Z","caller":"traceutil/trace.go:171","msg":"trace[1998286533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"131.22134ms","start":"2026-03-02T18:31:57.216886Z","end":"2026-03-02T18:31:57.348107Z","steps":["trace[1998286533] 'agreement among raft nodes before linearized reading' (duration: 131.057955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:57.348127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.311583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-02T18:31:57.348207Z","caller":"traceutil/trace.go:171","msg":"trace[1845936296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"131.420306ms","start":"2026-03-02T18:31:57.216754Z","end":"2026-03-02T18:31:57.348174Z","steps":["trace[1845936296] 'agreement among raft nodes before linearized reading' (duration: 131.21607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:57.992653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.871592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T18:31:57.99297Z","caller":"traceutil/trace.go:171","msg":"trace[572128412] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1004; }","duration":"503.944966ms","start":"2026-03-02T18:31:57.489004Z","end":"2026-03-02T18:31:57.992949Z","steps":["trace[572128412] 'read index received' (duration: 285.143901ms)","trace[572128412] 'applied index is now lower than readState.Index' (duration: 218.799835ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T18:31:57.993331Z","caller":"traceutil/trace.go:171","msg":"trace[195064737] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"641.89442ms","start":"2026-03-02T18:31:57.351418Z","end":"2026-03-02T18:31:57.993312Z","steps":["trace[195064737] 'process raft request' (duration: 102.256937ms)","trace[195064737] 'compare' (duration: 538.312867ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:31:57.993426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:57.351404Z","time spent":"641.976263ms","remote":"127.0.0.1:42818","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-03-02T18:31:57.993654Z","caller":"traceutil/trace.go:171","msg":"trace[651328447] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"642.177928ms","start":"2026-03-02T18:31:57.351461Z","end":"2026-03-02T18:31:57.993639Z","steps":["trace[651328447] 'process raft request' (duration: 641.331005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:57.99374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:57.351449Z","time spent":"642.247851ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T18:31:57.99391Z","caller":"traceutil/trace.go:171","msg":"trace[1586952626] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"642.434165ms","start":"2026-03-02T18:31:57.351462Z","end":"2026-03-02T18:31:57.993897Z","steps":["trace[1586952626] 'process raft request' (duration: 641.398777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:57.99399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:57.351448Z","time spent":"642.501178ms","remote":"127.0.0.1:42818","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-03-02T18:31:57.994211Z","caller":"traceutil/trace.go:171","msg":"trace[1720367354] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"642.23762ms","start":"2026-03-02T18:31:57.35196Z","end":"2026-03-02T18:31:57.994198Z","steps":["trace[1720367354] 'process raft request' (duration: 640.946215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:57.994291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:57.351945Z","time spent":"642.306322ms","remote":"127.0.0.1:42818","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-03-02T18:31:57.994544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.537251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:57.994598Z","caller":"traceutil/trace.go:171","msg":"trace[778566467] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:972; }","duration":"505.627143ms","start":"2026-03-02T18:31:57.488954Z","end":"2026-03-02T18:31:57.994581Z","steps":["trace[778566467] 'agreement among raft nodes before linearized reading' (duration: 505.544981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:57.994641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:57.488934Z","time spent":"505.694664ms","remote":"127.0.0.1:42846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-02T18:31:57.9949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.592527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T18:31:57.994955Z","caller":"traceutil/trace.go:171","msg":"trace[674917280] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:972; }","duration":"329.68017ms","start":"2026-03-02T18:31:57.665258Z","end":"2026-03-02T18:31:57.994939Z","steps":["trace[674917280] 'agreement among raft nodes before linearized reading' (duration: 329.571597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:57.994996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:57.665239Z","time spent":"329.744971ms","remote":"127.0.0.1:42902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-02T18:31:58.687964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.460979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:31:58.688075Z","caller":"traceutil/trace.go:171","msg":"trace[1360007565] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:972; }","duration":"319.626863ms","start":"2026-03-02T18:31:58.368417Z","end":"2026-03-02T18:31:58.688043Z","steps":["trace[1360007565] 'count revisions from in-memory index tree' (duration: 319.385456ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:31:58.688135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:31:58.368394Z","time spent":"319.724895ms","remote":"127.0.0.1:42768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-03-02T18:32:06.240066Z","caller":"traceutil/trace.go:171","msg":"trace[1505398177] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"367.331093ms","start":"2026-03-02T18:32:05.872676Z","end":"2026-03-02T18:32:06.240007Z","steps":["trace[1505398177] 'read index received' (duration: 367.132138ms)","trace[1505398177] 'applied index is now lower than readState.Index' (duration: 197.905µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:32:06.240218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.537328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T18:32:06.24026Z","caller":"traceutil/trace.go:171","msg":"trace[645801537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"367.630411ms","start":"2026-03-02T18:32:05.872618Z","end":"2026-03-02T18:32:06.240248Z","steps":["trace[645801537] 'agreement among raft nodes before linearized reading' (duration: 367.558169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:32:06.240292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:32:05.87259Z","time spent":"367.694663ms","remote":"127.0.0.1:42530","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-03-02T18:32:06.240461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.374084ms","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-03-02T18:32:06.240503Z","caller":"traceutil/trace.go:171","msg":"trace[136421407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"201.413645ms","start":"2026-03-02T18:32:06.039077Z","end":"2026-03-02T18:32:06.24049Z","steps":["trace[136421407] 'agreement among raft nodes before linearized reading' (duration: 201.273652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:32:06.240802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.468597ms","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-03-02T18:32:06.240909Z","caller":"traceutil/trace.go:171","msg":"trace[1732261282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"205.632591ms","start":"2026-03-02T18:32:06.035249Z","end":"2026-03-02T18:32:06.240881Z","steps":["trace[1732261282] 'agreement among raft nodes before linearized reading' (duration: 204.987653ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:32:06.241158Z","caller":"traceutil/trace.go:171","msg":"trace[1514276991] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"854.760182ms","start":"2026-03-02T18:32:05.386358Z","end":"2026-03-02T18:32:06.241118Z","steps":["trace[1514276991] 'process raft request' (duration: 853.434576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:32:06.241355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T18:32:05.386336Z","time spent":"854.920906ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T18:32:06.241627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.367895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-03-02T18:32:06.241861Z","caller":"traceutil/trace.go:171","msg":"trace[952473148] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1027; }","duration":"138.632273ms","start":"2026-03-02T18:32:06.103202Z","end":"2026-03-02T18:32:06.241834Z","steps":["trace[952473148] 'agreement among raft nodes before linearized reading' (duration: 137.263666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:32:06.241988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.583632ms","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-03-02T18:32:06.242659Z","caller":"traceutil/trace.go:171","msg":"trace[1795450462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"205.476086ms","start":"2026-03-02T18:32:06.037171Z","end":"2026-03-02T18:32:06.242647Z","steps":["trace[1795450462] 'agreement among raft nodes before linearized reading' (duration: 203.484742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T18:32:06.24328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.847227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-02T18:32:06.243436Z","caller":"traceutil/trace.go:171","msg":"trace[1079559242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"206.033282ms","start":"2026-03-02T18:32:06.037368Z","end":"2026-03-02T18:32:06.243402Z","steps":["trace[1079559242] 'agreement among raft nodes before linearized reading' (duration: 203.211225ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:35:16.410171Z","caller":"traceutil/trace.go:171","msg":"trace[41940260] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"127.26169ms","start":"2026-03-02T18:35:16.282877Z","end":"2026-03-02T18:35:16.410138Z","steps":["trace[41940260] 'process raft request' (duration: 127.085715ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:35:21.921158Z","caller":"traceutil/trace.go:171","msg":"trace[1088448807] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"165.291412ms","start":"2026-03-02T18:35:21.755833Z","end":"2026-03-02T18:35:21.921124Z","steps":["trace[1088448807] 'read index received' (duration: 165.22802ms)","trace[1088448807] 'applied index is now lower than readState.Index' (duration: 61.772µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T18:35:21.921483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.61025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-02T18:35:21.921713Z","caller":"traceutil/trace.go:171","msg":"trace[491395468] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1862; }","duration":"165.867707ms","start":"2026-03-02T18:35:21.755823Z","end":"2026-03-02T18:35:21.921691Z","steps":["trace[491395468] 'agreement among raft nodes before linearized reading' (duration: 165.547949ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T18:35:21.921935Z","caller":"traceutil/trace.go:171","msg":"trace[1162746417] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"207.974913ms","start":"2026-03-02T18:35:21.713901Z","end":"2026-03-02T18:35:21.921876Z","steps":["trace[1162746417] 'process raft request' (duration: 207.060407ms)"],"step_count":1}