{"level":"warn","ts":"2026-02-20T18:25:44.657884Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T18:25:44.657965Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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://162.253.55.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-20T18:25:44.658024Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T18:25:44.65804Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-20T18:25:44.658056Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-20T18:25:44.658681Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-20T18:25:44.658758Z","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://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-20T18:25:44.662183Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.205479ms"} {"level":"info","ts":"2026-02-20T18:25:44.668889Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-20T18:25:44.66896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T18:25:44.668982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-20T18:25:44.668997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T18:25:44.669009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-20T18:25:44.669051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-20T18:25:44.678052Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T18:25:44.679441Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T18:25:44.680852Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-20T18:25:44.683635Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T18:25:44.684369Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T18:25:44.684457Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:25:44.685254Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:25:44.685265Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:25:44.685643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-20T18:25:44.68592Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-20T18:25:44.689302Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-20T18:25:44.689519Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-20T18:25:44.689586Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T18:25:44.689623Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-20T18:25:44.689632Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T18:25:45.569736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T18:25:45.569792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T18:25:45.569808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-20T18:25:45.56982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-20T18:25:45.569825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-20T18:25:45.569834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-20T18:25:45.569841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-20T18:25:45.57161Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:25:45.572557Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:25:45.572552Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T18:25:45.57264Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:25:45.572855Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T18:25:45.5729Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T18:25:45.574635Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-02-20T18:25:45.575897Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T18:25:45.578495Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:25:45.578613Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:25:45.578653Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:26:13.235058Z","caller":"traceutil/trace.go:171","msg":"trace[1493488607] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"106.287236ms","start":"2026-02-20T18:26:13.128747Z","end":"2026-02-20T18:26:13.235034Z","steps":["trace[1493488607] 'read index received' (duration: 105.412472ms)","trace[1493488607] 'applied index is now lower than readState.Index' (duration: 873.754µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:26:13.235154Z","caller":"traceutil/trace.go:171","msg":"trace[462201744] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"352.018918ms","start":"2026-02-20T18:26:12.883085Z","end":"2026-02-20T18:26:13.235104Z","steps":["trace[462201744] 'process raft request' (duration: 351.620407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:13.235312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.548413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-20T18:26:13.236964Z","caller":"traceutil/trace.go:171","msg":"trace[1937407331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"107.299964ms","start":"2026-02-20T18:26:13.128698Z","end":"2026-02-20T18:26:13.235998Z","steps":["trace[1937407331] 'agreement among raft nodes before linearized reading' (duration: 106.483071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:13.23767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:12.883055Z","time spent":"352.739988ms","remote":"127.0.0.1:40698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317276,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:26:15.363879Z","caller":"traceutil/trace.go:171","msg":"trace[199672045] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"114.38559ms","start":"2026-02-20T18:26:15.249479Z","end":"2026-02-20T18:26:15.363864Z","steps":["trace[199672045] 'read index received' (duration: 114.243776ms)","trace[199672045] 'applied index is now lower than readState.Index' (duration: 141.284µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:26:15.363975Z","caller":"traceutil/trace.go:171","msg":"trace[877748918] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"147.203039ms","start":"2026-02-20T18:26:15.216732Z","end":"2026-02-20T18:26:15.363936Z","steps":["trace[877748918] 'process raft request' (duration: 147.034284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:15.364088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.591095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-20T18:26:15.364175Z","caller":"traceutil/trace.go:171","msg":"trace[158378934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"114.73603ms","start":"2026-02-20T18:26:15.249419Z","end":"2026-02-20T18:26:15.364155Z","steps":["trace[158378934] 'agreement among raft nodes before linearized reading' (duration: 114.518274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:15.364035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.355609ms","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-02-20T18:26:15.364285Z","caller":"traceutil/trace.go:171","msg":"trace[1120453771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"114.623796ms","start":"2026-02-20T18:26:15.249606Z","end":"2026-02-20T18:26:15.36423Z","steps":["trace[1120453771] 'agreement among raft nodes before linearized reading' (duration: 114.362389ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:22.827769Z","caller":"traceutil/trace.go:171","msg":"trace[686397388] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"153.094174ms","start":"2026-02-20T18:26:22.674651Z","end":"2026-02-20T18:26:22.827745Z","steps":["trace[686397388] 'read index received' (duration: 152.740664ms)","trace[686397388] 'applied index is now lower than readState.Index' (duration: 352.42µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:26:22.827873Z","caller":"traceutil/trace.go:171","msg":"trace[688207404] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"216.162112ms","start":"2026-02-20T18:26:22.611671Z","end":"2026-02-20T18:26:22.827833Z","steps":["trace[688207404] 'process raft request' (duration: 215.843144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:22.827938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.2634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:26:22.828096Z","caller":"traceutil/trace.go:171","msg":"trace[1056486038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"153.461544ms","start":"2026-02-20T18:26:22.674613Z","end":"2026-02-20T18:26:22.828074Z","steps":["trace[1056486038] 'agreement among raft nodes before linearized reading' (duration: 153.272659ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:23.013136Z","caller":"traceutil/trace.go:171","msg":"trace[935349758] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"173.391196ms","start":"2026-02-20T18:26:22.839721Z","end":"2026-02-20T18:26:23.013112Z","steps":["trace[935349758] 'process raft request' (duration: 166.504315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:24.177109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762842548494743,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:26:24.276988Z","caller":"traceutil/trace.go:171","msg":"trace[1991067012] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"765.898151ms","start":"2026-02-20T18:26:23.511031Z","end":"2026-02-20T18:26:24.276929Z","steps":["trace[1991067012] 'process raft request' (duration: 760.973425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:24.277354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:23.51101Z","time spent":"766.077667ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:26:24.896395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.402589ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T18:26:24.896486Z","caller":"traceutil/trace.go:171","msg":"trace[1273034216] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:554; }","duration":"1.220185153s","start":"2026-02-20T18:26:23.676284Z","end":"2026-02-20T18:26:24.896469Z","steps":["trace[1273034216] 'read index received' (duration: 595.714544ms)","trace[1273034216] 'applied index is now lower than readState.Index' (duration: 624.469148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:26:24.896847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220556814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:26:24.897143Z","caller":"traceutil/trace.go:171","msg":"trace[696790299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"1.22080218s","start":"2026-02-20T18:26:23.676234Z","end":"2026-02-20T18:26:24.897036Z","steps":["trace[696790299] 'agreement among raft nodes before linearized reading' (duration: 1.220565454s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:24.897198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:23.676214Z","time spent":"1.220971224s","remote":"127.0.0.1:40600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:26:27.126028Z","caller":"traceutil/trace.go:171","msg":"trace[93759198] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"160.528259ms","start":"2026-02-20T18:26:26.965479Z","end":"2026-02-20T18:26:27.126007Z","steps":["trace[93759198] 'read index received' (duration: 158.442671ms)","trace[93759198] 'applied index is now lower than readState.Index' (duration: 2.084638ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:26:27.126262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.759986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:26:27.126288Z","caller":"traceutil/trace.go:171","msg":"trace[1121782310] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"229.10511ms","start":"2026-02-20T18:26:26.897162Z","end":"2026-02-20T18:26:27.126267Z","steps":["trace[1121782310] 'process raft request' (duration: 226.756225ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:27.126342Z","caller":"traceutil/trace.go:171","msg":"trace[89571877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"160.866769ms","start":"2026-02-20T18:26:26.965437Z","end":"2026-02-20T18:26:27.126304Z","steps":["trace[89571877] 'agreement among raft nodes before linearized reading' (duration: 160.664394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:43.633113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.261379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:26:43.633184Z","caller":"traceutil/trace.go:171","msg":"trace[1497431179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:720; }","duration":"416.375493ms","start":"2026-02-20T18:26:43.216793Z","end":"2026-02-20T18:26:43.633169Z","steps":["trace[1497431179] 'range keys from in-memory index tree' (duration: 416.114356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:43.633215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:43.216775Z","time spent":"416.433074ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:26:43.972399Z","caller":"traceutil/trace.go:171","msg":"trace[351642541] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"335.457419ms","start":"2026-02-20T18:26:43.636919Z","end":"2026-02-20T18:26:43.972377Z","steps":["trace[351642541] 'process raft request' (duration: 335.328385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:43.972557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:43.636898Z","time spent":"335.567062ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:26:44.225016Z","caller":"traceutil/trace.go:171","msg":"trace[915482635] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"588.02065ms","start":"2026-02-20T18:26:43.636958Z","end":"2026-02-20T18:26:44.224978Z","steps":["trace[915482635] 'process raft request' (duration: 558.648786ms)","trace[915482635] 'compare' (duration: 29.158878ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:26:44.225165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:43.636943Z","time spent":"588.145694ms","remote":"127.0.0.1:40854","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-02-20T18:26:44.883175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.231077ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T18:26:44.883279Z","caller":"traceutil/trace.go:171","msg":"trace[780177323] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:744; }","duration":"1.243911393s","start":"2026-02-20T18:26:43.639351Z","end":"2026-02-20T18:26:44.883262Z","steps":["trace[780177323] 'read index received' (duration: 332.96682ms)","trace[780177323] 'applied index is now lower than readState.Index' (duration: 910.942963ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:26:44.883539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206453475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:26:44.883601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.523204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-20T18:26:44.883607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.277338ms","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-02-20T18:26:44.883613Z","caller":"traceutil/trace.go:171","msg":"trace[971345846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"1.206571188s","start":"2026-02-20T18:26:43.677021Z","end":"2026-02-20T18:26:44.883592Z","steps":["trace[971345846] 'agreement among raft nodes before linearized reading' (duration: 1.206446184s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:44.883658Z","caller":"traceutil/trace.go:171","msg":"trace[1713139145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:722; }","duration":"514.602315ms","start":"2026-02-20T18:26:44.369032Z","end":"2026-02-20T18:26:44.883634Z","steps":["trace[1713139145] 'agreement among raft nodes before linearized reading' (duration: 514.471331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:44.883663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:43.676999Z","time spent":"1.20665654s","remote":"127.0.0.1:40600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:26:44.883658Z","caller":"traceutil/trace.go:171","msg":"trace[972429327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:722; }","duration":"101.36822ms","start":"2026-02-20T18:26:44.782276Z","end":"2026-02-20T18:26:44.883644Z","steps":["trace[972429327] 'agreement among raft nodes before linearized reading' (duration: 101.211786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:44.883727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244325624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:26:44.883746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:44.369015Z","time spent":"514.718509ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T18:26:44.8838Z","caller":"traceutil/trace.go:171","msg":"trace[1044425995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:722; }","duration":"1.244479179s","start":"2026-02-20T18:26:43.639299Z","end":"2026-02-20T18:26:44.883778Z","steps":["trace[1044425995] 'agreement among raft nodes before linearized reading' (duration: 1.244214892s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:44.883844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:43.639281Z","time spent":"1.244552171s","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:26:45.057236Z","caller":"traceutil/trace.go:171","msg":"trace[499640971] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"168.44568ms","start":"2026-02-20T18:26:44.888758Z","end":"2026-02-20T18:26:45.057203Z","steps":["trace[499640971] 'process raft request' (duration: 119.475142ms)","trace[499640971] 'compare' (duration: 48.421572ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:26:45.05737Z","caller":"traceutil/trace.go:171","msg":"trace[768243500] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"168.196122ms","start":"2026-02-20T18:26:44.889148Z","end":"2026-02-20T18:26:45.057345Z","steps":["trace[768243500] 'process raft request' (duration: 168.09854ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:45.057532Z","caller":"traceutil/trace.go:171","msg":"trace[694421539] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:748; }","duration":"164.010516ms","start":"2026-02-20T18:26:44.893511Z","end":"2026-02-20T18:26:45.057522Z","steps":["trace[694421539] 'read index received' (duration: 51.923899ms)","trace[694421539] 'applied index is now lower than readState.Index' (duration: 112.085397ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:26:45.057683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.121309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:26:45.057721Z","caller":"traceutil/trace.go:171","msg":"trace[847536481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"164.220063ms","start":"2026-02-20T18:26:44.893492Z","end":"2026-02-20T18:26:45.057712Z","steps":["trace[847536481] 'agreement among raft nodes before linearized reading' (duration: 164.11103ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:46.350193Z","caller":"traceutil/trace.go:171","msg":"trace[843788455] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"120.923752ms","start":"2026-02-20T18:26:46.229242Z","end":"2026-02-20T18:26:46.350165Z","steps":["trace[843788455] 'read index received' (duration: 120.781908ms)","trace[843788455] 'applied index is now lower than readState.Index' (duration: 140.714µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:26:46.350281Z","caller":"traceutil/trace.go:171","msg":"trace[1968450762] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"371.347163ms","start":"2026-02-20T18:26:45.978909Z","end":"2026-02-20T18:26:46.350256Z","steps":["trace[1968450762] 'process raft request' (duration: 371.106456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:46.350412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:45.978888Z","time spent":"371.450596ms","remote":"127.0.0.1:40854","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-02-20T18:26:46.350389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.130748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:26:46.350461Z","caller":"traceutil/trace.go:171","msg":"trace[1494898194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"121.256361ms","start":"2026-02-20T18:26:46.229192Z","end":"2026-02-20T18:26:46.350449Z","steps":["trace[1494898194] 'agreement among raft nodes before linearized reading' (duration: 121.073306ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:46.600394Z","caller":"traceutil/trace.go:171","msg":"trace[825589899] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"246.633377ms","start":"2026-02-20T18:26:46.353738Z","end":"2026-02-20T18:26:46.600372Z","steps":["trace[825589899] 'process raft request' (duration: 154.267527ms)","trace[825589899] 'compare' (duration: 92.247027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:26:47.176469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.343507ms","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-02-20T18:26:47.176557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.602035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:26:47.176626Z","caller":"traceutil/trace.go:171","msg":"trace[1780523871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"115.654616ms","start":"2026-02-20T18:26:47.060955Z","end":"2026-02-20T18:26:47.176609Z","steps":["trace[1780523871] 'range keys from in-memory index tree' (duration: 114.956137ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:47.176627Z","caller":"traceutil/trace.go:171","msg":"trace[171035295] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"115.709047ms","start":"2026-02-20T18:26:47.0609Z","end":"2026-02-20T18:26:47.176609Z","steps":["trace[171035295] 'range keys from in-memory index tree' (duration: 115.040299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:47.176552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.818676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:26:47.176681Z","caller":"traceutil/trace.go:171","msg":"trace[395916614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"227.98319ms","start":"2026-02-20T18:26:46.948691Z","end":"2026-02-20T18:26:47.176674Z","steps":["trace[395916614] 'range keys from in-memory index tree' (duration: 227.202848ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:07.528767Z","caller":"traceutil/trace.go:171","msg":"trace[2039083990] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"144.613836ms","start":"2026-02-20T18:27:07.384119Z","end":"2026-02-20T18:27:07.528733Z","steps":["trace[2039083990] 'process raft request' (duration: 103.289591ms)","trace[2039083990] 'compare' (duration: 41.190091ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:07.529689Z","caller":"traceutil/trace.go:171","msg":"trace[332762830] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"144.05769ms","start":"2026-02-20T18:27:07.385578Z","end":"2026-02-20T18:27:07.529636Z","steps":["trace[332762830] 'process raft request' (duration: 143.642718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:09.078449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762842548495299,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:27:09.190041Z","caller":"traceutil/trace.go:171","msg":"trace[815149986] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"734.080282ms","start":"2026-02-20T18:27:08.455547Z","end":"2026-02-20T18:27:09.189627Z","steps":["trace[815149986] 'process raft request' (duration: 733.928428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:09.190203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:08.455521Z","time spent":"734.602977ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:27:09.195796Z","caller":"traceutil/trace.go:171","msg":"trace[184066924] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:822; }","duration":"617.538254ms","start":"2026-02-20T18:27:08.578229Z","end":"2026-02-20T18:27:09.195767Z","steps":["trace[184066924] 'read index received' (duration: 611.207609ms)","trace[184066924] 'applied index is now lower than readState.Index' (duration: 6.329105ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:09.19615Z","caller":"traceutil/trace.go:171","msg":"trace[584126275] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"376.930449ms","start":"2026-02-20T18:27:08.819199Z","end":"2026-02-20T18:27:09.19613Z","steps":["trace[584126275] 'process raft request' (duration: 376.423385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:09.196218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.04045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:27:09.196285Z","caller":"traceutil/trace.go:171","msg":"trace[411435889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"491.146073ms","start":"2026-02-20T18:27:08.70512Z","end":"2026-02-20T18:27:09.196266Z","steps":["trace[411435889] 'agreement among raft nodes before linearized reading' (duration: 490.965618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:09.196282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:08.819168Z","time spent":"377.030962ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:27:09.196325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:08.705092Z","time spent":"491.224946ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:27:09.196182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.937825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T18:27:09.196543Z","caller":"traceutil/trace.go:171","msg":"trace[1853646846] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:794; }","duration":"618.332156ms","start":"2026-02-20T18:27:08.578183Z","end":"2026-02-20T18:27:09.196515Z","steps":["trace[1853646846] 'agreement among raft nodes before linearized reading' (duration: 617.847403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:09.196618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:08.578166Z","time spent":"618.43861ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.49\" "} {"level":"warn","ts":"2026-02-20T18:27:09.334939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.495736ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-20T18:27:09.335247Z","caller":"traceutil/trace.go:171","msg":"trace[260235971] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"135.594745ms","start":"2026-02-20T18:27:09.19963Z","end":"2026-02-20T18:27:09.335224Z","steps":["trace[260235971] 'process raft request' (duration: 135.40561ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:09.796015Z","caller":"traceutil/trace.go:171","msg":"trace[294434539] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"119.586912ms","start":"2026-02-20T18:27:09.676404Z","end":"2026-02-20T18:27:09.795991Z","steps":["trace[294434539] 'read index received' (duration: 119.300094ms)","trace[294434539] 'applied index is now lower than readState.Index' (duration: 285.658µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:09.796204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.772567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:09.796247Z","caller":"traceutil/trace.go:171","msg":"trace[2139641822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"119.86599ms","start":"2026-02-20T18:27:09.676368Z","end":"2026-02-20T18:27:09.796234Z","steps":["trace[2139641822] 'agreement among raft nodes before linearized reading' (duration: 119.714536ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:09.796352Z","caller":"traceutil/trace.go:171","msg":"trace[1275344923] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"259.129807ms","start":"2026-02-20T18:27:09.53696Z","end":"2026-02-20T18:27:09.79609Z","steps":["trace[1275344923] 'process raft request' (duration: 258.823549ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:11.390307Z","caller":"traceutil/trace.go:171","msg":"trace[1904395522] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"193.988053ms","start":"2026-02-20T18:27:11.196281Z","end":"2026-02-20T18:27:11.390269Z","steps":["trace[1904395522] 'process raft request' (duration: 193.784848ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:11.552593Z","caller":"traceutil/trace.go:171","msg":"trace[654338913] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"158.027287ms","start":"2026-02-20T18:27:11.394529Z","end":"2026-02-20T18:27:11.552557Z","steps":["trace[654338913] 'process raft request' (duration: 157.576905ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:11.831216Z","caller":"traceutil/trace.go:171","msg":"trace[2091821235] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"157.614356ms","start":"2026-02-20T18:27:11.673585Z","end":"2026-02-20T18:27:11.8312Z","steps":["trace[2091821235] 'read index received' (duration: 84.064639ms)","trace[2091821235] 'applied index is now lower than readState.Index' (duration: 73.546677ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:11.831335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.747679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:11.831357Z","caller":"traceutil/trace.go:171","msg":"trace[1083677710] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:804; }","duration":"157.832501ms","start":"2026-02-20T18:27:11.673517Z","end":"2026-02-20T18:27:11.83135Z","steps":["trace[1083677710] 'agreement among raft nodes before linearized reading' (duration: 157.777059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:11.83136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.195414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:11.831363Z","caller":"traceutil/trace.go:171","msg":"trace[1132545524] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"159.679933ms","start":"2026-02-20T18:27:11.671628Z","end":"2026-02-20T18:27:11.831308Z","steps":["trace[1132545524] 'process raft request' (duration: 86.098375ms)","trace[1132545524] 'compare' (duration: 73.362122ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:11.831424Z","caller":"traceutil/trace.go:171","msg":"trace[1090374622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"157.299946ms","start":"2026-02-20T18:27:11.674104Z","end":"2026-02-20T18:27:11.831404Z","steps":["trace[1090374622] 'agreement among raft nodes before linearized reading' (duration: 157.206094ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:12.580122Z","caller":"traceutil/trace.go:171","msg":"trace[1765760559] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"744.644004ms","start":"2026-02-20T18:27:11.835441Z","end":"2026-02-20T18:27:12.580085Z","steps":["trace[1765760559] 'process raft request' (duration: 712.74417ms)","trace[1765760559] 'compare' (duration: 31.330188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:12.58048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:11.835416Z","time spent":"744.988744ms","remote":"127.0.0.1:40854","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-02-20T18:27:18.737599Z","caller":"traceutil/trace.go:171","msg":"trace[255015623] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"125.116676ms","start":"2026-02-20T18:27:18.612427Z","end":"2026-02-20T18:27:18.737544Z","steps":["trace[255015623] 'process raft request' (duration: 81.523548ms)","trace[255015623] 'compare' (duration: 43.434463ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:18.741625Z","caller":"traceutil/trace.go:171","msg":"trace[1672181620] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"126.910235ms","start":"2026-02-20T18:27:18.614698Z","end":"2026-02-20T18:27:18.741608Z","steps":["trace[1672181620] 'process raft request' (duration: 126.684869ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:25.105684Z","caller":"traceutil/trace.go:171","msg":"trace[1556924392] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"340.534432ms","start":"2026-02-20T18:27:24.765127Z","end":"2026-02-20T18:27:25.105661Z","steps":["trace[1556924392] 'process raft request' (duration: 340.371137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:25.105853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:24.765102Z","time spent":"340.680676ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:27:36.059623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762842548495607,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:27:36.472581Z","caller":"traceutil/trace.go:171","msg":"trace[2123196958] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"913.606743ms","start":"2026-02-20T18:27:35.558952Z","end":"2026-02-20T18:27:36.472558Z","steps":["trace[2123196958] 'read index received' (duration: 913.386607ms)","trace[2123196958] 'applied index is now lower than readState.Index' (duration: 219.226µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:36.472608Z","caller":"traceutil/trace.go:171","msg":"trace[915950030] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"945.070315ms","start":"2026-02-20T18:27:35.52752Z","end":"2026-02-20T18:27:36.47259Z","steps":["trace[915950030] 'process raft request' (duration: 944.88341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:36.472725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.764388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:36.472762Z","caller":"traceutil/trace.go:171","msg":"trace[541136199] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:919; }","duration":"913.84471ms","start":"2026-02-20T18:27:35.558909Z","end":"2026-02-20T18:27:36.472753Z","steps":["trace[541136199] 'agreement among raft nodes before linearized reading' (duration: 913.771398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:36.472799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.558893Z","time spent":"913.897422ms","remote":"127.0.0.1:41050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:27:36.472846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.057286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:27:36.472922Z","caller":"traceutil/trace.go:171","msg":"trace[1063422990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"710.136878ms","start":"2026-02-20T18:27:35.762754Z","end":"2026-02-20T18:27:36.47289Z","steps":["trace[1063422990] 'agreement among raft nodes before linearized reading' (duration: 709.986753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:36.472956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.762741Z","time spent":"710.20807ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:27:36.472948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.030305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:27:36.472991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.696761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:27:36.473015Z","caller":"traceutil/trace.go:171","msg":"trace[1640070850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"797.103077ms","start":"2026-02-20T18:27:35.675893Z","end":"2026-02-20T18:27:36.472996Z","steps":["trace[1640070850] 'agreement among raft nodes before linearized reading' (duration: 796.999734ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:36.47305Z","caller":"traceutil/trace.go:171","msg":"trace[518125656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"759.784584ms","start":"2026-02-20T18:27:35.713248Z","end":"2026-02-20T18:27:36.473033Z","steps":["trace[518125656] 'agreement among raft nodes before linearized reading' (duration: 759.63951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:36.473099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.713228Z","time spent":"759.862935ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:27:36.472835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.676635ms","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":"info","ts":"2026-02-20T18:27:36.473195Z","caller":"traceutil/trace.go:171","msg":"trace[308675124] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:919; }","duration":"884.097556ms","start":"2026-02-20T18:27:35.589073Z","end":"2026-02-20T18:27:36.473171Z","steps":["trace[308675124] 'agreement among raft nodes before linearized reading' (duration: 883.661134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:36.473204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.650794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:36.473241Z","caller":"traceutil/trace.go:171","msg":"trace[1816178357] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:919; }","duration":"657.712626ms","start":"2026-02-20T18:27:35.815519Z","end":"2026-02-20T18:27:36.473232Z","steps":["trace[1816178357] 'agreement among raft nodes before linearized reading' (duration: 657.648654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:36.473246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.589054Z","time spent":"884.177478ms","remote":"127.0.0.1:40766","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":"warn","ts":"2026-02-20T18:27:36.47327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.815505Z","time spent":"657.757517ms","remote":"127.0.0.1:40702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:27:36.473196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.173316ms","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-02-20T18:27:36.473342Z","caller":"traceutil/trace.go:171","msg":"trace[1548972924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"633.336611ms","start":"2026-02-20T18:27:35.839987Z","end":"2026-02-20T18:27:36.473324Z","steps":["trace[1548972924] 'agreement among raft nodes before linearized reading' (duration: 633.132525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:36.47338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.839972Z","time spent":"633.397833ms","remote":"127.0.0.1:40854","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-02-20T18:27:36.47338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.527491Z","time spent":"945.172768ms","remote":"127.0.0.1:40854","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-02-20T18:27:36.473056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.675849Z","time spent":"797.19896ms","remote":"127.0.0.1:40600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:27:36.695036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.354504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:27:36.695333Z","caller":"traceutil/trace.go:171","msg":"trace[771333028] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"218.590955ms","start":"2026-02-20T18:27:36.476714Z","end":"2026-02-20T18:27:36.695305Z","steps":["trace[771333028] 'process raft request' (duration: 97.883152ms)","trace[771333028] 'compare' (duration: 120.207289ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:36.695459Z","caller":"traceutil/trace.go:171","msg":"trace[1032621061] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"218.111971ms","start":"2026-02-20T18:27:36.477313Z","end":"2026-02-20T18:27:36.695425Z","steps":["trace[1032621061] 'process raft request' (duration: 217.883935ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:36.695606Z","caller":"traceutil/trace.go:171","msg":"trace[1489363442] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"217.273888ms","start":"2026-02-20T18:27:36.478307Z","end":"2026-02-20T18:27:36.695581Z","steps":["trace[1489363442] 'process raft request' (duration: 216.95829ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:45.019774Z","caller":"traceutil/trace.go:171","msg":"trace[687756352] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"287.04158ms","start":"2026-02-20T18:27:44.732714Z","end":"2026-02-20T18:27:45.019756Z","steps":["trace[687756352] 'process raft request' (duration: 286.944378ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:45.047369Z","caller":"traceutil/trace.go:171","msg":"trace[977741914] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"313.39435ms","start":"2026-02-20T18:27:44.733961Z","end":"2026-02-20T18:27:45.047355Z","steps":["trace[977741914] 'process raft request' (duration: 313.258446ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:45.047377Z","caller":"traceutil/trace.go:171","msg":"trace[1238766807] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"314.155292ms","start":"2026-02-20T18:27:44.73319Z","end":"2026-02-20T18:27:45.047345Z","steps":["trace[1238766807] 'process raft request' (duration: 313.834093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:45.047472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:44.733952Z","time spent":"313.463042ms","remote":"127.0.0.1:40854","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-02-20T18:27:45.047547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:44.733165Z","time spent":"314.291445ms","remote":"127.0.0.1:40854","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-02-20T18:27:45.047586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.982457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:45.047636Z","caller":"traceutil/trace.go:171","msg":"trace[286200946] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:945; }","duration":"140.11126ms","start":"2026-02-20T18:27:44.907515Z","end":"2026-02-20T18:27:45.047627Z","steps":["trace[286200946] 'agreement among raft nodes before linearized reading' (duration: 139.987957ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:45.047322Z","caller":"traceutil/trace.go:171","msg":"trace[1042953258] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:983; }","duration":"139.72991ms","start":"2026-02-20T18:27:44.907561Z","end":"2026-02-20T18:27:45.047291Z","steps":["trace[1042953258] 'read index received' (duration: 112.395673ms)","trace[1042953258] 'applied index is now lower than readState.Index' (duration: 27.332857ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:46.673836Z","caller":"traceutil/trace.go:171","msg":"trace[1068247663] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"105.569914ms","start":"2026-02-20T18:27:46.568232Z","end":"2026-02-20T18:27:46.673802Z","steps":["trace[1068247663] 'process raft request' (duration: 105.40956ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:47.231791Z","caller":"traceutil/trace.go:171","msg":"trace[219671788] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"171.660714ms","start":"2026-02-20T18:27:47.060112Z","end":"2026-02-20T18:27:47.231772Z","steps":["trace[219671788] 'process raft request' (duration: 171.521401ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:47.299639Z","caller":"traceutil/trace.go:171","msg":"trace[1048912826] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"238.515007ms","start":"2026-02-20T18:27:47.061071Z","end":"2026-02-20T18:27:47.299586Z","steps":["trace[1048912826] 'process raft request' (duration: 213.955716ms)","trace[1048912826] 'compare' (duration: 24.349335ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:49.525001Z","caller":"traceutil/trace.go:171","msg":"trace[1910161606] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"277.451504ms","start":"2026-02-20T18:27:49.247525Z","end":"2026-02-20T18:27:49.524977Z","steps":["trace[1910161606] 'read index received' (duration: 277.207728ms)","trace[1910161606] 'applied index is now lower than readState.Index' (duration: 242.806µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:49.525123Z","caller":"traceutil/trace.go:171","msg":"trace[725671404] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"285.548888ms","start":"2026-02-20T18:27:49.239541Z","end":"2026-02-20T18:27:49.52509Z","steps":["trace[725671404] 'process raft request' (duration: 285.276861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:49.525193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.63718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:49.525233Z","caller":"traceutil/trace.go:171","msg":"trace[1337619569] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:955; }","duration":"277.721272ms","start":"2026-02-20T18:27:49.247498Z","end":"2026-02-20T18:27:49.525219Z","steps":["trace[1337619569] 'agreement among raft nodes before linearized reading' (duration: 277.620569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:49.525281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.872495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-20T18:27:49.525325Z","caller":"traceutil/trace.go:171","msg":"trace[303607384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"221.929667ms","start":"2026-02-20T18:27:49.303384Z","end":"2026-02-20T18:27:49.525314Z","steps":["trace[303607384] 'agreement among raft nodes before linearized reading' (duration: 221.763822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:49.525427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.894129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-20T18:27:49.525392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.375881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:49.525453Z","caller":"traceutil/trace.go:171","msg":"trace[1193181130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"159.95678ms","start":"2026-02-20T18:27:49.365491Z","end":"2026-02-20T18:27:49.525448Z","steps":["trace[1193181130] 'agreement among raft nodes before linearized reading' (duration: 159.898039ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:49.52548Z","caller":"traceutil/trace.go:171","msg":"trace[1067053041] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:955; }","duration":"139.483124ms","start":"2026-02-20T18:27:49.385978Z","end":"2026-02-20T18:27:49.525461Z","steps":["trace[1067053041] 'agreement among raft nodes before linearized reading' (duration: 139.306939ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:50.896347Z","caller":"traceutil/trace.go:171","msg":"trace[1382291790] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"205.035489ms","start":"2026-02-20T18:27:50.691014Z","end":"2026-02-20T18:27:50.896049Z","steps":["trace[1382291790] 'process raft request' (duration: 204.823984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.179241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.40664ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T18:27:54.179331Z","caller":"traceutil/trace.go:171","msg":"trace[671735834] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"119.397411ms","start":"2026-02-20T18:27:54.059918Z","end":"2026-02-20T18:27:54.179316Z","steps":["trace[671735834] 'read index received' (duration: 96.162µs)","trace[671735834] 'applied index is now lower than readState.Index' (duration: 119.300069ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:54.179427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.510555ms","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-02-20T18:27:54.17945Z","caller":"traceutil/trace.go:171","msg":"trace[2112872327] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:989; }","duration":"119.595627ms","start":"2026-02-20T18:27:54.059845Z","end":"2026-02-20T18:27:54.17944Z","steps":["trace[2112872327] 'agreement among raft nodes before linearized reading' (duration: 119.511825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:56.081113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.249098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:27:56.081217Z","caller":"traceutil/trace.go:171","msg":"trace[736415308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:990; }","duration":"994.406513ms","start":"2026-02-20T18:27:55.086787Z","end":"2026-02-20T18:27:56.081193Z","steps":["trace[736415308] 'range keys from in-memory index tree' (duration: 994.000911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:56.081266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:55.086766Z","time spent":"994.489845ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:27:56.081559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.699045ms","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-02-20T18:27:56.081659Z","caller":"traceutil/trace.go:171","msg":"trace[1862882022] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"533.859929ms","start":"2026-02-20T18:27:55.547774Z","end":"2026-02-20T18:27:56.081634Z","steps":["trace[1862882022] 'range keys from in-memory index tree' (duration: 533.463768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:56.081712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:55.547752Z","time spent":"533.950872ms","remote":"127.0.0.1:40854","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-02-20T18:27:56.08185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.352844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:27:56.081903Z","caller":"traceutil/trace.go:171","msg":"trace[1378561759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"462.426975ms","start":"2026-02-20T18:27:55.619464Z","end":"2026-02-20T18:27:56.081891Z","steps":["trace[1378561759] 'range keys from in-memory index tree' (duration: 462.257471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:56.081935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:55.61945Z","time spent":"462.478817ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:27:56.082331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.051402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:56.082372Z","caller":"traceutil/trace.go:171","msg":"trace[900286874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"406.117485ms","start":"2026-02-20T18:27:55.676244Z","end":"2026-02-20T18:27:56.082362Z","steps":["trace[900286874] 'range keys from in-memory index tree' (duration: 405.9749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:56.082399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:55.676227Z","time spent":"406.165995ms","remote":"127.0.0.1:40600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:27:56.082448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.821497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:27:56.082495Z","caller":"traceutil/trace.go:171","msg":"trace[31364692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"462.899309ms","start":"2026-02-20T18:27:55.619581Z","end":"2026-02-20T18:27:56.08248Z","steps":["trace[31364692] 'range keys from in-memory index tree' (duration: 462.661412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:56.082525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:55.619559Z","time spent":"462.959561ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:27:56.082495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.491917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:56.082939Z","caller":"traceutil/trace.go:171","msg":"trace[1802429747] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:990; }","duration":"774.049503ms","start":"2026-02-20T18:27:55.308711Z","end":"2026-02-20T18:27:56.08276Z","steps":["trace[1802429747] 'count revisions from in-memory index tree' (duration: 773.338082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:56.08339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:55.308688Z","time spent":"774.639049ms","remote":"127.0.0.1:46374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:27:56.536752Z","caller":"traceutil/trace.go:171","msg":"trace[848491640] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"450.186914ms","start":"2026-02-20T18:27:56.086541Z","end":"2026-02-20T18:27:56.536728Z","steps":["trace[848491640] 'process raft request' (duration: 450.050621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:56.536913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:56.086527Z","time spent":"450.313468ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:27:56.722625Z","caller":"traceutil/trace.go:171","msg":"trace[2004796787] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1032; }","duration":"297.919744ms","start":"2026-02-20T18:27:56.424683Z","end":"2026-02-20T18:27:56.722603Z","steps":["trace[2004796787] 'read index received' (duration: 111.894352ms)","trace[2004796787] 'applied index is now lower than readState.Index' (duration: 186.024422ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:56.722761Z","caller":"traceutil/trace.go:171","msg":"trace[1760885224] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"635.687721ms","start":"2026-02-20T18:27:56.086958Z","end":"2026-02-20T18:27:56.722646Z","steps":["trace[1760885224] 'process raft request' (duration: 635.329511ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:56.72288Z","caller":"traceutil/trace.go:171","msg":"trace[815130746] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"634.360964ms","start":"2026-02-20T18:27:56.088466Z","end":"2026-02-20T18:27:56.722827Z","steps":["trace[815130746] 'process raft request' (duration: 634.107237ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:56.72296Z","caller":"traceutil/trace.go:171","msg":"trace[1624799863] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"634.884209ms","start":"2026-02-20T18:27:56.088049Z","end":"2026-02-20T18:27:56.722933Z","steps":["trace[1624799863] 'process raft request' (duration: 634.491038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:56.72299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:56.086949Z","time spent":"635.914527ms","remote":"127.0.0.1:40854","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-02-20T18:27:56.723052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:56.088457Z","time spent":"634.472847ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:27:56.723237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:56.088038Z","time spent":"635.032383ms","remote":"127.0.0.1:40854","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-02-20T18:27:56.72333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.619174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:56.723468Z","caller":"traceutil/trace.go:171","msg":"trace[448389893] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:994; }","duration":"298.754228ms","start":"2026-02-20T18:27:56.424622Z","end":"2026-02-20T18:27:56.723376Z","steps":["trace[448389893] 'agreement among raft nodes before linearized reading' (duration: 298.604134ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:58.75151Z","caller":"traceutil/trace.go:171","msg":"trace[1372773148] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"171.161857ms","start":"2026-02-20T18:27:58.580326Z","end":"2026-02-20T18:27:58.751488Z","steps":["trace[1372773148] 'read index received' (duration: 170.996312ms)","trace[1372773148] 'applied index is now lower than readState.Index' (duration: 164.635µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:58.751553Z","caller":"traceutil/trace.go:171","msg":"trace[788455639] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"207.44805ms","start":"2026-02-20T18:27:58.544078Z","end":"2026-02-20T18:27:58.751526Z","steps":["trace[788455639] 'process raft request' (duration: 207.264825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:58.751752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.408934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T18:27:58.751797Z","caller":"traceutil/trace.go:171","msg":"trace[1642585402] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:996; }","duration":"171.495396ms","start":"2026-02-20T18:27:58.58029Z","end":"2026-02-20T18:27:58.751785Z","steps":["trace[1642585402] 'agreement among raft nodes before linearized reading' (duration: 171.346392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:59.544978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762842548495810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:59.990431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.391589ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:59.990716Z","caller":"traceutil/trace.go:171","msg":"trace[287925546] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:996; }","duration":"297.642686ms","start":"2026-02-20T18:27:59.693002Z","end":"2026-02-20T18:27:59.990645Z","steps":["trace[287925546] 'range keys from in-memory index tree' (duration: 297.356778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:59.990535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.348948ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-20T18:27:59.991689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:58.752691Z","time spent":"1.238991439s","remote":"127.0.0.1:40622","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T18:27:59.991792Z","caller":"traceutil/trace.go:171","msg":"trace[1516693038] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.235670816s","start":"2026-02-20T18:27:58.756105Z","end":"2026-02-20T18:27:59.991776Z","steps":["trace[1516693038] 'process raft request' (duration: 1.235349877s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:59.991801Z","caller":"traceutil/trace.go:171","msg":"trace[382402935] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.235223504s","start":"2026-02-20T18:27:58.756558Z","end":"2026-02-20T18:27:59.991782Z","steps":["trace[382402935] 'process raft request' (duration: 1.23507908s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:59.991846Z","caller":"traceutil/trace.go:171","msg":"trace[778596556] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"1.235211123s","start":"2026-02-20T18:27:58.756616Z","end":"2026-02-20T18:27:59.991827Z","steps":["trace[778596556] 'process raft request' (duration: 1.23507636s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:59.991896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:58.756087Z","time spent":"1.235746978s","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:27:59.991913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:58.756548Z","time spent":"1.235306006s","remote":"127.0.0.1:40854","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-02-20T18:27:59.991938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:58.756609Z","time spent":"1.235282295s","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:27:59.992018Z","caller":"traceutil/trace.go:171","msg":"trace[375653433] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1038; }","duration":"947.747091ms","start":"2026-02-20T18:27:59.044071Z","end":"2026-02-20T18:27:59.991818Z","steps":["trace[375653433] 'read index received' (duration: 644.110167ms)","trace[375653433] 'applied index is now lower than readState.Index' (duration: 303.635054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:59.992169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.08857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:27:59.992248Z","caller":"traceutil/trace.go:171","msg":"trace[1005305062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"948.217354ms","start":"2026-02-20T18:27:59.044013Z","end":"2026-02-20T18:27:59.99223Z","steps":["trace[1005305062] 'agreement among raft nodes before linearized reading' (duration: 947.932446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:59.992289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:59.043986Z","time spent":"948.294166ms","remote":"127.0.0.1:40854","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":"info","ts":"2026-02-20T18:28:00.353058Z","caller":"traceutil/trace.go:171","msg":"trace[1901152136] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"360.906643ms","start":"2026-02-20T18:27:59.992122Z","end":"2026-02-20T18:28:00.353029Z","steps":["trace[1901152136] 'read index received' (duration: 332.85458ms)","trace[1901152136] 'applied index is now lower than readState.Index' (duration: 28.050943ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:00.353087Z","caller":"traceutil/trace.go:171","msg":"trace[1208188477] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"659.163677ms","start":"2026-02-20T18:27:59.693886Z","end":"2026-02-20T18:28:00.35305Z","steps":["trace[1208188477] 'process raft request' (duration: 631.108764ms)","trace[1208188477] 'compare' (duration: 27.853437ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:00.353212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.987944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:00.353247Z","caller":"traceutil/trace.go:171","msg":"trace[157005210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1000; }","duration":"677.064917ms","start":"2026-02-20T18:27:59.676169Z","end":"2026-02-20T18:28:00.353234Z","steps":["trace[157005210] 'agreement among raft nodes before linearized reading' (duration: 676.963494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.353258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:59.693863Z","time spent":"659.306221ms","remote":"127.0.0.1:40672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:28:00.353286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:59.676154Z","time spent":"677.124818ms","remote":"127.0.0.1:40600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:28:00.722296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.553707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:00.722687Z","caller":"traceutil/trace.go:171","msg":"trace[134018700] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1043; }","duration":"368.184136ms","start":"2026-02-20T18:28:00.354403Z","end":"2026-02-20T18:28:00.722587Z","steps":["trace[134018700] 'read index received' (duration: 114.28676ms)","trace[134018700] 'applied index is now lower than readState.Index' (duration: 253.896426ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:00.722698Z","caller":"traceutil/trace.go:171","msg":"trace[950887560] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"729.50686ms","start":"2026-02-20T18:27:59.993158Z","end":"2026-02-20T18:28:00.722665Z","steps":["trace[950887560] 'process raft request' (duration: 475.48031ms)","trace[950887560] 'compare' (duration: 253.424883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:00.722785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.387281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:00.722817Z","caller":"traceutil/trace.go:171","msg":"trace[1175738061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"368.461993ms","start":"2026-02-20T18:28:00.354344Z","end":"2026-02-20T18:28:00.722806Z","steps":["trace[1175738061] 'agreement among raft nodes before linearized reading' (duration: 368.399741ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:00.722775Z","caller":"traceutil/trace.go:171","msg":"trace[1248034989] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"726.198537ms","start":"2026-02-20T18:27:59.99655Z","end":"2026-02-20T18:28:00.722749Z","steps":["trace[1248034989] 'process raft request' (duration: 725.874478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.722852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.354326Z","time spent":"368.517925ms","remote":"127.0.0.1:40596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:28:00.722828Z","caller":"traceutil/trace.go:171","msg":"trace[1805502381] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"571.88613ms","start":"2026-02-20T18:28:00.150907Z","end":"2026-02-20T18:28:00.722793Z","steps":["trace[1805502381] 'process raft request' (duration: 571.635363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.72287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:59.99312Z","time spent":"729.641004ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:00.722987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.150879Z","time spent":"572.023525ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:00.723907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:59.996533Z","time spent":"726.344571ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:00.945477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.042601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:00.945608Z","caller":"traceutil/trace.go:171","msg":"trace[1099610981] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"185.896469ms","start":"2026-02-20T18:28:00.759691Z","end":"2026-02-20T18:28:00.945588Z","steps":["trace[1099610981] 'process raft request' (duration: 75.627961ms)","trace[1099610981] 'compare' (duration: 109.892867ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:02.28284Z","caller":"traceutil/trace.go:171","msg":"trace[293254178] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"283.720239ms","start":"2026-02-20T18:28:01.999091Z","end":"2026-02-20T18:28:02.282811Z","steps":["trace[293254178] 'process raft request' (duration: 283.485422ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:02.552393Z","caller":"traceutil/trace.go:171","msg":"trace[809669638] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"551.575904ms","start":"2026-02-20T18:28:02.000794Z","end":"2026-02-20T18:28:02.55237Z","steps":["trace[809669638] 'process raft request' (duration: 551.506042ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:02.552467Z","caller":"traceutil/trace.go:171","msg":"trace[2048429967] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"552.87443ms","start":"2026-02-20T18:28:01.999554Z","end":"2026-02-20T18:28:02.552428Z","steps":["trace[2048429967] 'process raft request' (duration: 523.800899ms)","trace[2048429967] 'compare' (duration: 28.809724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:02.55255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:02.00078Z","time spent":"551.685437ms","remote":"127.0.0.1:40854","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-02-20T18:28:02.552643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:01.999376Z","time spent":"553.166468ms","remote":"127.0.0.1:40854","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-02-20T18:28:02.599481Z","caller":"traceutil/trace.go:171","msg":"trace[2137337488] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1048; }","duration":"117.472199ms","start":"2026-02-20T18:28:02.481985Z","end":"2026-02-20T18:28:02.599457Z","steps":["trace[2137337488] 'read index received' (duration: 41.384015ms)","trace[2137337488] 'applied index is now lower than readState.Index' (duration: 76.086884ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:02.599524Z","caller":"traceutil/trace.go:171","msg":"trace[1063059016] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"194.115938ms","start":"2026-02-20T18:28:02.405374Z","end":"2026-02-20T18:28:02.59949Z","steps":["trace[1063059016] 'process raft request' (duration: 193.966414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:02.599628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.618763ms","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":"info","ts":"2026-02-20T18:28:02.599661Z","caller":"traceutil/trace.go:171","msg":"trace[538796252] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1008; }","duration":"117.700985ms","start":"2026-02-20T18:28:02.481951Z","end":"2026-02-20T18:28:02.599651Z","steps":["trace[538796252] 'agreement among raft nodes before linearized reading' (duration: 117.602333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:03.026806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.499358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:03.026914Z","caller":"traceutil/trace.go:171","msg":"trace[1204535195] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"368.452323ms","start":"2026-02-20T18:28:02.65844Z","end":"2026-02-20T18:28:03.026892Z","steps":["trace[1204535195] 'process raft request' (duration: 168.780401ms)","trace[1204535195] 'compare' (duration: 199.139257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:03.026968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:02.658424Z","time spent":"368.521895ms","remote":"127.0.0.1:40672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:28:03.332559Z","caller":"traceutil/trace.go:171","msg":"trace[1803472049] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1052; }","duration":"671.84429ms","start":"2026-02-20T18:28:02.660683Z","end":"2026-02-20T18:28:03.332527Z","steps":["trace[1803472049] 'read index received' (duration: 166.62174ms)","trace[1803472049] 'applied index is now lower than readState.Index' (duration: 505.22091ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:03.332652Z","caller":"traceutil/trace.go:171","msg":"trace[577009029] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"672.91651ms","start":"2026-02-20T18:28:02.6597Z","end":"2026-02-20T18:28:03.332616Z","steps":["trace[577009029] 'process raft request' (duration: 672.594631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:03.332794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.072686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:03.333095Z","caller":"traceutil/trace.go:171","msg":"trace[2045084681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"672.424986ms","start":"2026-02-20T18:28:02.660652Z","end":"2026-02-20T18:28:03.333077Z","steps":["trace[2045084681] 'agreement among raft nodes before linearized reading' (duration: 672.059666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:03.333156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:02.660639Z","time spent":"672.503508ms","remote":"127.0.0.1:40600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:28:03.333077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:02.659681Z","time spent":"673.247099ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:03.3445Z","caller":"traceutil/trace.go:171","msg":"trace[661423551] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"510.22931ms","start":"2026-02-20T18:28:02.834232Z","end":"2026-02-20T18:28:03.344461Z","steps":["trace[661423551] 'process raft request' (duration: 510.069566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:03.344598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.336231ms","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-02-20T18:28:03.344634Z","caller":"traceutil/trace.go:171","msg":"trace[683553436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"616.410363ms","start":"2026-02-20T18:28:02.728214Z","end":"2026-02-20T18:28:03.344624Z","steps":["trace[683553436] 'agreement among raft nodes before linearized reading' (duration: 616.287589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:03.344661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:02.834195Z","time spent":"510.390505ms","remote":"127.0.0.1:40672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:28:03.344796Z","caller":"traceutil/trace.go:171","msg":"trace[1936823490] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"430.744142ms","start":"2026-02-20T18:28:02.91404Z","end":"2026-02-20T18:28:03.344784Z","steps":["trace[1936823490] 'process raft request' (duration: 430.335341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:03.344889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:02.914019Z","time spent":"430.817434ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:03.344672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:02.728195Z","time spent":"616.465835ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:28:03.345107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.241141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:28:03.345439Z","caller":"traceutil/trace.go:171","msg":"trace[1668516898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"395.315003ms","start":"2026-02-20T18:28:02.949822Z","end":"2026-02-20T18:28:03.345137Z","steps":["trace[1668516898] 'agreement among raft nodes before linearized reading' (duration: 395.227371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:03.34552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:02.949801Z","time spent":"395.697513ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:28:04.176669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762842548495846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:28:04.588397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188001386s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:04.588502Z","caller":"traceutil/trace.go:171","msg":"trace[2091281094] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.238784513s","start":"2026-02-20T18:28:03.349699Z","end":"2026-02-20T18:28:04.588483Z","steps":["trace[2091281094] 'process raft request' (duration: 50.700305ms)","trace[2091281094] 'compare' (duration: 1.187653456s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:04.588556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:03.349677Z","time spent":"1.238854435s","remote":"127.0.0.1:40672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:28:04.590249Z","caller":"traceutil/trace.go:171","msg":"trace[2088350629] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.240423009s","start":"2026-02-20T18:28:03.349802Z","end":"2026-02-20T18:28:04.590225Z","steps":["trace[2088350629] 'process raft request' (duration: 1.240374208s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:04.590294Z","caller":"traceutil/trace.go:171","msg":"trace[714399941] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.240545622s","start":"2026-02-20T18:28:03.349728Z","end":"2026-02-20T18:28:04.590274Z","steps":["trace[714399941] 'process raft request' (duration: 1.240321746s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:04.590416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:03.349708Z","time spent":"1.240631714s","remote":"127.0.0.1:40854","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-02-20T18:28:04.59042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:03.349797Z","time spent":"1.240526402s","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:04.606219Z","caller":"traceutil/trace.go:171","msg":"trace[1270596540] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1057; }","duration":"930.451347ms","start":"2026-02-20T18:28:03.675738Z","end":"2026-02-20T18:28:04.60619Z","steps":["trace[1270596540] 'read index received' (duration: 914.41497ms)","trace[1270596540] 'applied index is now lower than readState.Index' (duration: 16.034657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:04.606488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.741246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:04.606553Z","caller":"traceutil/trace.go:171","msg":"trace[463625120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"930.841738ms","start":"2026-02-20T18:28:03.675694Z","end":"2026-02-20T18:28:04.606536Z","steps":["trace[463625120] 'agreement among raft nodes before linearized reading' (duration: 930.722795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:04.606594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:03.675676Z","time spent":"930.91005ms","remote":"127.0.0.1:40600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:28:04.606752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.057524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:28:04.606796Z","caller":"traceutil/trace.go:171","msg":"trace[1867507958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"319.155497ms","start":"2026-02-20T18:28:04.287629Z","end":"2026-02-20T18:28:04.606785Z","steps":["trace[1867507958] 'agreement among raft nodes before linearized reading' (duration: 318.92581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:04.606986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:04.28761Z","time spent":"319.361263ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:28:58.716031Z","caller":"traceutil/trace.go:171","msg":"trace[439162676] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"108.138411ms","start":"2026-02-20T18:28:58.607854Z","end":"2026-02-20T18:28:58.715992Z","steps":["trace[439162676] 'process raft request' (duration: 64.177707ms)","trace[439162676] 'compare' (duration: 43.756598ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:55.97376Z","caller":"traceutil/trace.go:171","msg":"trace[1156072402] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"131.062006ms","start":"2026-02-20T18:30:55.842659Z","end":"2026-02-20T18:30:55.973721Z","steps":["trace[1156072402] 'process raft request' (duration: 72.214254ms)","trace[1156072402] 'compare' (duration: 58.673837ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:56.179501Z","caller":"traceutil/trace.go:171","msg":"trace[22213286] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"101.688096ms","start":"2026-02-20T18:30:56.077779Z","end":"2026-02-20T18:30:56.179467Z","steps":["trace[22213286] 'process raft request' (duration: 55.120737ms)","trace[22213286] 'compare' (duration: 46.392814ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:56.17954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.23894ms","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-02-20T18:30:56.179653Z","caller":"traceutil/trace.go:171","msg":"trace[1225031297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1739; }","duration":"103.431066ms","start":"2026-02-20T18:30:56.076197Z","end":"2026-02-20T18:30:56.179628Z","steps":["trace[1225031297] 'range keys from in-memory index tree' (duration: 102.954402ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:26.177065Z","caller":"traceutil/trace.go:171","msg":"trace[797544038] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"144.269472ms","start":"2026-02-20T18:31:26.03276Z","end":"2026-02-20T18:31:26.17703Z","steps":["trace[797544038] 'read index received' (duration: 48.28001ms)","trace[797544038] 'applied index is now lower than readState.Index' (duration: 95.988042ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:26.177189Z","caller":"traceutil/trace.go:171","msg":"trace[1865164136] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"202.354465ms","start":"2026-02-20T18:31:25.974779Z","end":"2026-02-20T18:31:26.177133Z","steps":["trace[1865164136] 'process raft request' (duration: 106.240649ms)","trace[1865164136] 'compare' (duration: 95.862379ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:26.177343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.55697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-20T18:31:26.17739Z","caller":"traceutil/trace.go:171","msg":"trace[1017206552] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1891; }","duration":"144.650252ms","start":"2026-02-20T18:31:26.032724Z","end":"2026-02-20T18:31:26.177374Z","steps":["trace[1017206552] 'agreement among raft nodes before linearized reading' (duration: 144.433316ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:26.522999Z","caller":"traceutil/trace.go:171","msg":"trace[172532022] linearizableReadLoop","detail":"{readStateIndex:1977; appliedIndex:1976; }","duration":"334.284362ms","start":"2026-02-20T18:31:26.188684Z","end":"2026-02-20T18:31:26.522969Z","steps":["trace[172532022] 'read index received' (duration: 320.082115ms)","trace[172532022] 'applied index is now lower than readState.Index' (duration: 14.201237ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:26.523078Z","caller":"traceutil/trace.go:171","msg":"trace[2021062672] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"339.21789ms","start":"2026-02-20T18:31:26.183809Z","end":"2026-02-20T18:31:26.523027Z","steps":["trace[2021062672] 'process raft request' (duration: 325.097615ms)","trace[2021062672] 'compare' (duration: 13.924459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:26.523297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.59522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-20T18:31:26.523325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:26.183781Z","time spent":"339.420905ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:31:26.523401Z","caller":"traceutil/trace.go:171","msg":"trace[851144471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1892; }","duration":"334.753394ms","start":"2026-02-20T18:31:26.188623Z","end":"2026-02-20T18:31:26.523376Z","steps":["trace[851144471] 'agreement among raft nodes before linearized reading' (duration: 334.465596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:26.52341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.783166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-20T18:31:26.523449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:26.188594Z","time spent":"334.846767ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T18:31:26.523466Z","caller":"traceutil/trace.go:171","msg":"trace[1142877888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1892; }","duration":"183.91421ms","start":"2026-02-20T18:31:26.339534Z","end":"2026-02-20T18:31:26.523449Z","steps":["trace[1142877888] 'agreement among raft nodes before linearized reading' (duration: 183.683754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:26.523538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.729194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-20T18:31:26.523584Z","caller":"traceutil/trace.go:171","msg":"trace[1909708744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1892; }","duration":"183.837898ms","start":"2026-02-20T18:31:26.339734Z","end":"2026-02-20T18:31:26.523572Z","steps":["trace[1909708744] 'agreement among raft nodes before linearized reading' (duration: 183.701974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:26.934453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.798275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:31:26.934768Z","caller":"traceutil/trace.go:171","msg":"trace[422079027] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1977; }","duration":"258.788462ms","start":"2026-02-20T18:31:26.675955Z","end":"2026-02-20T18:31:26.934743Z","steps":["trace[422079027] 'read index received' (duration: 73.569716ms)","trace[422079027] 'applied index is now lower than readState.Index' (duration: 185.217046ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:26.934873Z","caller":"traceutil/trace.go:171","msg":"trace[83936162] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"407.230872ms","start":"2026-02-20T18:31:26.527629Z","end":"2026-02-20T18:31:26.93486Z","steps":["trace[83936162] 'process raft request' (duration: 221.907052ms)","trace[83936162] 'compare' (duration: 184.659791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:26.934945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:26.527606Z","time spent":"407.297834ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:31:26.935403Z","caller":"traceutil/trace.go:171","msg":"trace[1198518724] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"406.444959ms","start":"2026-02-20T18:31:26.528945Z","end":"2026-02-20T18:31:26.93539Z","steps":["trace[1198518724] 'process raft request' (duration: 405.654927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:26.93548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:26.528934Z","time spent":"406.512192ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:31:26.935654Z","caller":"traceutil/trace.go:171","msg":"trace[1294782475] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"406.574754ms","start":"2026-02-20T18:31:26.529069Z","end":"2026-02-20T18:31:26.935644Z","steps":["trace[1294782475] 'process raft request' (duration: 405.608917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:26.93572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:26.52904Z","time spent":"406.640486ms","remote":"127.0.0.1:40854","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-02-20T18:31:26.935901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.953936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:26.935939Z","caller":"traceutil/trace.go:171","msg":"trace[30285425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1895; }","duration":"260.044998ms","start":"2026-02-20T18:31:26.675884Z","end":"2026-02-20T18:31:26.935929Z","steps":["trace[30285425] 'agreement among raft nodes before linearized reading' (duration: 259.984596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:26.936115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.110551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:26.93615Z","caller":"traceutil/trace.go:171","msg":"trace[851963036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1895; }","duration":"235.210373ms","start":"2026-02-20T18:31:26.70093Z","end":"2026-02-20T18:31:26.936141Z","steps":["trace[851963036] 'agreement among raft nodes before linearized reading' (duration: 235.160422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:26.936527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.418811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-20T18:31:26.936568Z","caller":"traceutil/trace.go:171","msg":"trace[1615396366] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1895; }","duration":"132.461332ms","start":"2026-02-20T18:31:26.804097Z","end":"2026-02-20T18:31:26.936558Z","steps":["trace[1615396366] 'agreement among raft nodes before linearized reading' (duration: 132.334289ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:46.317804Z","caller":"traceutil/trace.go:171","msg":"trace[1539220096] linearizableReadLoop","detail":"{readStateIndex:2069; appliedIndex:2068; }","duration":"184.848036ms","start":"2026-02-20T18:31:46.132934Z","end":"2026-02-20T18:31:46.317782Z","steps":["trace[1539220096] 'read index received' (duration: 184.588389ms)","trace[1539220096] 'applied index is now lower than readState.Index' (duration: 258.767µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:46.31793Z","caller":"traceutil/trace.go:171","msg":"trace[243000185] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"197.16503ms","start":"2026-02-20T18:31:46.120709Z","end":"2026-02-20T18:31:46.317874Z","steps":["trace[243000185] 'process raft request' (duration: 196.868642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.318076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.436046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:999"} {"level":"warn","ts":"2026-02-20T18:31:46.318144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.180646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:12 size:8411"} {"level":"info","ts":"2026-02-20T18:31:46.318188Z","caller":"traceutil/trace.go:171","msg":"trace[1873253781] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:12; response_revision:1979; }","duration":"185.306999ms","start":"2026-02-20T18:31:46.132867Z","end":"2026-02-20T18:31:46.318174Z","steps":["trace[1873253781] 'agreement among raft nodes before linearized reading' (duration: 185.056512ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:46.318173Z","caller":"traceutil/trace.go:171","msg":"trace[1715884162] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1979; }","duration":"183.5769ms","start":"2026-02-20T18:31:46.134575Z","end":"2026-02-20T18:31:46.318152Z","steps":["trace[1715884162] 'agreement among raft nodes before linearized reading' (duration: 183.400075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.318398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.976319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"warn","ts":"2026-02-20T18:31:46.318475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.760199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/\" range_end:\"/registry/roles/kube-system0\" limit:500 ","response":"range_response_count:12 size:6839"} {"level":"info","ts":"2026-02-20T18:31:46.318532Z","caller":"traceutil/trace.go:171","msg":"trace[102142267] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1979; }","duration":"127.155664ms","start":"2026-02-20T18:31:46.191337Z","end":"2026-02-20T18:31:46.318492Z","steps":["trace[102142267] 'agreement among raft nodes before linearized reading' (duration: 126.840745ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:46.318572Z","caller":"traceutil/trace.go:171","msg":"trace[769944665] range","detail":"{range_begin:/registry/roles/kube-system/; range_end:/registry/roles/kube-system0; response_count:12; response_revision:1979; }","duration":"162.927543ms","start":"2026-02-20T18:31:46.155622Z","end":"2026-02-20T18:31:46.31855Z","steps":["trace[769944665] 'agreement among raft nodes before linearized reading' (duration: 162.560193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.532913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.25112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-public/\" range_end:\"/registry/serviceaccounts/kube-public0\" limit:500 ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-20T18:31:46.533026Z","caller":"traceutil/trace.go:171","msg":"trace[2116983839] range","detail":"{range_begin:/registry/serviceaccounts/kube-public/; range_end:/registry/serviceaccounts/kube-public0; response_count:1; response_revision:1979; }","duration":"101.448035ms","start":"2026-02-20T18:31:46.431549Z","end":"2026-02-20T18:31:46.532997Z","steps":["trace[2116983839] 'range keys from in-memory index tree' (duration: 101.063535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.533261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.255502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363630"} {"level":"warn","ts":"2026-02-20T18:31:46.533271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.790619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:31:46.533376Z","caller":"traceutil/trace.go:171","msg":"trace[1475767182] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1979; }","duration":"100.391596ms","start":"2026-02-20T18:31:46.432957Z","end":"2026-02-20T18:31:46.533349Z","steps":["trace[1475767182] 'range keys from in-memory index tree' (duration: 99.972864ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:46.533392Z","caller":"traceutil/trace.go:171","msg":"trace[1000546457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1979; }","duration":"182.966663ms","start":"2026-02-20T18:31:46.350398Z","end":"2026-02-20T18:31:46.533365Z","steps":["trace[1000546457] 'range keys from in-memory index tree' (duration: 182.570533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.533316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.025987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:31:46.533492Z","caller":"traceutil/trace.go:171","msg":"trace[651426198] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1979; }","duration":"187.238383ms","start":"2026-02-20T18:31:46.346238Z","end":"2026-02-20T18:31:46.533477Z","steps":["trace[651426198] 'count revisions from in-memory index tree' (duration: 186.946905ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:46.750287Z","caller":"traceutil/trace.go:171","msg":"trace[50846763] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"186.867223ms","start":"2026-02-20T18:31:46.56339Z","end":"2026-02-20T18:31:46.750257Z","steps":["trace[50846763] 'read index received' (duration: 186.740169ms)","trace[50846763] 'applied index is now lower than readState.Index' (duration: 125.684µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:46.750434Z","caller":"traceutil/trace.go:171","msg":"trace[1995915116] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"208.366214ms","start":"2026-02-20T18:31:46.542056Z","end":"2026-02-20T18:31:46.750422Z","steps":["trace[1995915116] 'process raft request' (duration: 208.028414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.750692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.284994ms","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:536"} {"level":"info","ts":"2026-02-20T18:31:46.750731Z","caller":"traceutil/trace.go:171","msg":"trace[1245656890] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1980; }","duration":"187.381737ms","start":"2026-02-20T18:31:46.563335Z","end":"2026-02-20T18:31:46.750717Z","steps":["trace[1245656890] 'agreement among raft nodes before linearized reading' (duration: 187.235143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.751045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.448909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9324"} {"level":"info","ts":"2026-02-20T18:31:46.751117Z","caller":"traceutil/trace.go:171","msg":"trace[756695988] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1980; }","duration":"109.589172ms","start":"2026-02-20T18:31:46.64151Z","end":"2026-02-20T18:31:46.751099Z","steps":["trace[756695988] 'agreement among raft nodes before linearized reading' (duration: 109.289774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.751228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.396581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-public/\" range_end:\"/registry/services/specs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:46.75135Z","caller":"traceutil/trace.go:171","msg":"trace[940432826] range","detail":"{range_begin:/registry/services/specs/kube-public/; range_end:/registry/services/specs/kube-public0; response_count:0; response_revision:1980; }","duration":"107.575777ms","start":"2026-02-20T18:31:46.643744Z","end":"2026-02-20T18:31:46.75132Z","steps":["trace[940432826] 'agreement among raft nodes before linearized reading' (duration: 107.292669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.751485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.806821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14326"} {"level":"info","ts":"2026-02-20T18:31:46.75156Z","caller":"traceutil/trace.go:171","msg":"trace[68965220] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1980; }","duration":"108.916724ms","start":"2026-02-20T18:31:46.642624Z","end":"2026-02-20T18:31:46.751541Z","steps":["trace[68965220] 'agreement among raft nodes before linearized reading' (duration: 108.665047ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:46.913732Z","caller":"traceutil/trace.go:171","msg":"trace[1697073811] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2070; }","duration":"153.034746ms","start":"2026-02-20T18:31:46.760663Z","end":"2026-02-20T18:31:46.913698Z","steps":["trace[1697073811] 'read index received' (duration: 87.012911ms)","trace[1697073811] 'applied index is now lower than readState.Index' (duration: 66.020025ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:46.913949Z","caller":"traceutil/trace.go:171","msg":"trace[1123146360] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"157.244693ms","start":"2026-02-20T18:31:46.75669Z","end":"2026-02-20T18:31:46.913935Z","steps":["trace[1123146360] 'process raft request' (duration: 90.978481ms)","trace[1123146360] 'compare' (duration: 65.855301ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:46.914374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.052498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:12 size:88631"} {"level":"info","ts":"2026-02-20T18:31:46.914461Z","caller":"traceutil/trace.go:171","msg":"trace[27577244] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:12; response_revision:1981; }","duration":"152.157941ms","start":"2026-02-20T18:31:46.762284Z","end":"2026-02-20T18:31:46.914442Z","steps":["trace[27577244] 'agreement among raft nodes before linearized reading' (duration: 151.792101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:46.914587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.880769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14326"} {"level":"info","ts":"2026-02-20T18:31:46.914718Z","caller":"traceutil/trace.go:171","msg":"trace[1007375538] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1981; }","duration":"154.059954ms","start":"2026-02-20T18:31:46.760631Z","end":"2026-02-20T18:31:46.914691Z","steps":["trace[1007375538] 'agreement among raft nodes before linearized reading' (duration: 153.719405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:48.874767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762842548498928,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:31:48.988277Z","caller":"traceutil/trace.go:171","msg":"trace[293979786] linearizableReadLoop","detail":"{readStateIndex:2077; appliedIndex:2076; }","duration":"614.032601ms","start":"2026-02-20T18:31:48.374214Z","end":"2026-02-20T18:31:48.988247Z","steps":["trace[293979786] 'read index received' (duration: 613.723602ms)","trace[293979786] 'applied index is now lower than readState.Index' (duration: 307.479µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:48.988432Z","caller":"traceutil/trace.go:171","msg":"trace[1396624383] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"661.757246ms","start":"2026-02-20T18:31:48.32666Z","end":"2026-02-20T18:31:48.988418Z","steps":["trace[1396624383] 'process raft request' (duration: 661.345044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:48.988594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:48.326643Z","time spent":"661.837847ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:48.988744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.719573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6684"} {"level":"info","ts":"2026-02-20T18:31:48.988846Z","caller":"traceutil/trace.go:171","msg":"trace[483158383] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1987; }","duration":"608.881448ms","start":"2026-02-20T18:31:48.379938Z","end":"2026-02-20T18:31:48.988819Z","steps":["trace[483158383] 'agreement among raft nodes before linearized reading' (duration: 608.660312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:48.9889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:48.379915Z","time spent":"608.97604ms","remote":"127.0.0.1:40794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6707,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-20T18:31:48.989318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.996602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:31:48.989409Z","caller":"traceutil/trace.go:171","msg":"trace[1248007507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1987; }","duration":"233.150836ms","start":"2026-02-20T18:31:48.756234Z","end":"2026-02-20T18:31:48.989384Z","steps":["trace[1248007507] 'agreement among raft nodes before linearized reading' (duration: 232.700193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:48.989489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.678268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T18:31:48.989531Z","caller":"traceutil/trace.go:171","msg":"trace[720906799] range","detail":"{range_begin:/registry/masterleases/162.253.55.49; range_end:; response_count:1; response_revision:1987; }","duration":"400.76026ms","start":"2026-02-20T18:31:48.58876Z","end":"2026-02-20T18:31:48.98952Z","steps":["trace[720906799] 'agreement among raft nodes before linearized reading' (duration: 400.610086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:48.989573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:48.58873Z","time spent":"400.837683ms","remote":"127.0.0.1:40622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.49\" "} {"level":"warn","ts":"2026-02-20T18:31:48.989345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.367098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:31:48.98959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.752579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-20T18:31:48.989667Z","caller":"traceutil/trace.go:171","msg":"trace[1351144015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1987; }","duration":"313.679667ms","start":"2026-02-20T18:31:48.675969Z","end":"2026-02-20T18:31:48.989649Z","steps":["trace[1351144015] 'agreement among raft nodes before linearized reading' (duration: 313.346698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:48.98952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.322127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7036"} {"level":"info","ts":"2026-02-20T18:31:48.98976Z","caller":"traceutil/trace.go:171","msg":"trace[116123393] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1987; }","duration":"615.604705ms","start":"2026-02-20T18:31:48.374144Z","end":"2026-02-20T18:31:48.989749Z","steps":["trace[116123393] 'agreement among raft nodes before linearized reading' (duration: 615.324957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:48.989804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:48.374119Z","time spent":"615.674527ms","remote":"127.0.0.1:40794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7059,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-20T18:31:48.989713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:48.675895Z","time spent":"313.806641ms","remote":"127.0.0.1:40600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:31:48.989707Z","caller":"traceutil/trace.go:171","msg":"trace[444472545] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1987; }","duration":"118.925324ms","start":"2026-02-20T18:31:48.870751Z","end":"2026-02-20T18:31:48.989676Z","steps":["trace[444472545] 'agreement among raft nodes before linearized reading' (duration: 118.550283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:48.989632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.101189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-20T18:31:48.990454Z","caller":"traceutil/trace.go:171","msg":"trace[1015290839] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1987; }","duration":"494.965163ms","start":"2026-02-20T18:31:48.49547Z","end":"2026-02-20T18:31:48.990435Z","steps":["trace[1015290839] 'agreement among raft nodes before linearized reading' (duration: 494.099079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:48.99051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:48.495443Z","time spent":"495.049406ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-20T18:31:51.351478Z","caller":"traceutil/trace.go:171","msg":"trace[89012036] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2087; }","duration":"348.51355ms","start":"2026-02-20T18:31:51.002929Z","end":"2026-02-20T18:31:51.351443Z","steps":["trace[89012036] 'read index received' (duration: 348.341425ms)","trace[89012036] 'applied index is now lower than readState.Index' (duration: 170.925µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:51.351808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.83111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:856"} {"level":"info","ts":"2026-02-20T18:31:51.351866Z","caller":"traceutil/trace.go:171","msg":"trace[986828828] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:1997; }","duration":"348.938192ms","start":"2026-02-20T18:31:51.002911Z","end":"2026-02-20T18:31:51.351849Z","steps":["trace[986828828] 'agreement among raft nodes before linearized reading' (duration: 348.764418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:51.35191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:51.002905Z","time spent":"348.993294ms","remote":"127.0.0.1:40908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":879,"request content":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" "} {"level":"warn","ts":"2026-02-20T18:31:51.352091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.816616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:31:51.352179Z","caller":"traceutil/trace.go:171","msg":"trace[883437423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1997; }","duration":"291.95218ms","start":"2026-02-20T18:31:51.060209Z","end":"2026-02-20T18:31:51.352161Z","steps":["trace[883437423] 'agreement among raft nodes before linearized reading' (duration: 291.749264ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:51.352192Z","caller":"traceutil/trace.go:171","msg":"trace[1998687780] transaction","detail":"{read_only:false; response_revision:1997; number_of_response:1; }","duration":"356.562116ms","start":"2026-02-20T18:31:50.995616Z","end":"2026-02-20T18:31:51.352178Z","steps":["trace[1998687780] 'process raft request' (duration: 355.63421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:51.35231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:50.995601Z","time spent":"356.621067ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:51.352447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.130322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:31:51.352489Z","caller":"traceutil/trace.go:171","msg":"trace[208453833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1997; }","duration":"230.198224ms","start":"2026-02-20T18:31:51.122279Z","end":"2026-02-20T18:31:51.352477Z","steps":["trace[208453833] 'agreement among raft nodes before linearized reading' (duration: 230.074641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:51.352594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.80073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:688"} {"level":"info","ts":"2026-02-20T18:31:51.35266Z","caller":"traceutil/trace.go:171","msg":"trace[766517518] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:1997; }","duration":"289.904702ms","start":"2026-02-20T18:31:51.062735Z","end":"2026-02-20T18:31:51.35264Z","steps":["trace[766517518] 'agreement among raft nodes before linearized reading' (duration: 289.726648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:51.352714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.988246ms","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-02-20T18:31:51.352695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.934666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-20T18:31:51.352747Z","caller":"traceutil/trace.go:171","msg":"trace[88146797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1997; }","duration":"182.060698ms","start":"2026-02-20T18:31:51.170677Z","end":"2026-02-20T18:31:51.352737Z","steps":["trace[88146797] 'agreement among raft nodes before linearized reading' (duration: 181.957205ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:51.3528Z","caller":"traceutil/trace.go:171","msg":"trace[1513996582] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1997; }","duration":"206.08624ms","start":"2026-02-20T18:31:51.146686Z","end":"2026-02-20T18:31:51.352772Z","steps":["trace[1513996582] 'agreement among raft nodes before linearized reading' (duration: 205.458203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:51.353116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.862936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:31:51.353232Z","caller":"traceutil/trace.go:171","msg":"trace[1976826338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1997; }","duration":"273.023921ms","start":"2026-02-20T18:31:51.080182Z","end":"2026-02-20T18:31:51.353206Z","steps":["trace[1976826338] 'agreement among raft nodes before linearized reading' (duration: 272.696412ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:51.530826Z","caller":"traceutil/trace.go:171","msg":"trace[1291688205] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"172.219193ms","start":"2026-02-20T18:31:51.35857Z","end":"2026-02-20T18:31:51.53079Z","steps":["trace[1291688205] 'process raft request' (duration: 118.954205ms)","trace[1291688205] 'compare' (duration: 53.120394ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:51.558531Z","caller":"traceutil/trace.go:171","msg":"trace[598856438] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"199.899107ms","start":"2026-02-20T18:31:51.35861Z","end":"2026-02-20T18:31:51.558509Z","steps":["trace[598856438] 'process raft request' (duration: 199.768223ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:51.558915Z","caller":"traceutil/trace.go:171","msg":"trace[689708413] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"199.846275ms","start":"2026-02-20T18:31:51.359055Z","end":"2026-02-20T18:31:51.558901Z","steps":["trace[689708413] 'process raft request' (duration: 199.413793ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:51.558995Z","caller":"traceutil/trace.go:171","msg":"trace[1171355215] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"199.801654ms","start":"2026-02-20T18:31:51.359161Z","end":"2026-02-20T18:31:51.558963Z","steps":["trace[1171355215] 'process raft request' (duration: 199.599138ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:51.55911Z","caller":"traceutil/trace.go:171","msg":"trace[399132127] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2088; }","duration":"129.631703ms","start":"2026-02-20T18:31:51.429453Z","end":"2026-02-20T18:31:51.559085Z","steps":["trace[399132127] 'read index received' (duration: 48.138576ms)","trace[399132127] 'applied index is now lower than readState.Index' (duration: 81.491287ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:51.559243Z","caller":"traceutil/trace.go:171","msg":"trace[951778273] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"198.648901ms","start":"2026-02-20T18:31:51.360547Z","end":"2026-02-20T18:31:51.559195Z","steps":["trace[951778273] 'process raft request' (duration: 198.364783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:51.559322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.844069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:688"} {"level":"warn","ts":"2026-02-20T18:31:51.559382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.137241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:856"} {"level":"info","ts":"2026-02-20T18:31:51.559444Z","caller":"traceutil/trace.go:171","msg":"trace[298263110] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:2002; }","duration":"128.240664ms","start":"2026-02-20T18:31:51.431184Z","end":"2026-02-20T18:31:51.559425Z","steps":["trace[298263110] 'agreement among raft nodes before linearized reading' (duration: 128.11875ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:51.559457Z","caller":"traceutil/trace.go:171","msg":"trace[1678227139] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:2002; }","duration":"130.016553ms","start":"2026-02-20T18:31:51.429395Z","end":"2026-02-20T18:31:51.559411Z","steps":["trace[1678227139] 'agreement among raft nodes before linearized reading' (duration: 129.856599ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:54.251561Z","caller":"traceutil/trace.go:171","msg":"trace[99367658] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"188.052046ms","start":"2026-02-20T18:31:54.063472Z","end":"2026-02-20T18:31:54.251524Z","steps":["trace[99367658] 'process raft request' (duration: 187.858741ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:54.481456Z","caller":"traceutil/trace.go:171","msg":"trace[836438325] linearizableReadLoop","detail":"{readStateIndex:2104; appliedIndex:2101; }","duration":"374.718173ms","start":"2026-02-20T18:31:54.106713Z","end":"2026-02-20T18:31:54.481431Z","steps":["trace[836438325] 'read index received' (duration: 144.53134ms)","trace[836438325] 'applied index is now lower than readState.Index' (duration: 230.185763ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:54.481485Z","caller":"traceutil/trace.go:171","msg":"trace[1450781568] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"417.429026ms","start":"2026-02-20T18:31:54.064023Z","end":"2026-02-20T18:31:54.481452Z","steps":["trace[1450781568] 'process raft request' (duration: 374.527957ms)","trace[1450781568] 'compare' (duration: 42.464297ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:54.481614Z","caller":"traceutil/trace.go:171","msg":"trace[693119185] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"416.904433ms","start":"2026-02-20T18:31:54.064696Z","end":"2026-02-20T18:31:54.4816Z","steps":["trace[693119185] 'process raft request' (duration: 416.496431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:54.481648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:54.06401Z","time spent":"417.54662ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:54.48174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:54.064682Z","time spent":"416.964334ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:31:54.481946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.228347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-02-20T18:31:54.481936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.442908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-20T18:31:54.482006Z","caller":"traceutil/trace.go:171","msg":"trace[2135593668] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:2012; }","duration":"375.316219ms","start":"2026-02-20T18:31:54.106674Z","end":"2026-02-20T18:31:54.48199Z","steps":["trace[2135593668] 'agreement among raft nodes before linearized reading' (duration: 375.170885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:54.48204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:54.106656Z","time spent":"375.377852ms","remote":"127.0.0.1:40802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-02-20T18:31:54.482045Z","caller":"traceutil/trace.go:171","msg":"trace[1740920986] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:2012; }","duration":"295.610632ms","start":"2026-02-20T18:31:54.186404Z","end":"2026-02-20T18:31:54.482015Z","steps":["trace[1740920986] 'agreement among raft nodes before linearized reading' (duration: 295.394656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:56.365911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.968376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:132 size:100866"} {"level":"warn","ts":"2026-02-20T18:31:56.365986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.719958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:132 size:100866"} {"level":"info","ts":"2026-02-20T18:31:56.366041Z","caller":"traceutil/trace.go:171","msg":"trace[1506931989] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:132; response_revision:2019; }","duration":"162.176402ms","start":"2026-02-20T18:31:56.203839Z","end":"2026-02-20T18:31:56.366015Z","steps":["trace[1506931989] 'range keys from in-memory index tree' (duration: 161.067462ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:56.366107Z","caller":"traceutil/trace.go:171","msg":"trace[1955040493] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:132; response_revision:2019; }","duration":"157.925684ms","start":"2026-02-20T18:31:56.208144Z","end":"2026-02-20T18:31:56.366069Z","steps":["trace[1955040493] 'range keys from in-memory index tree' (duration: 156.810202ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:32:03.731967Z","caller":"traceutil/trace.go:171","msg":"trace[1048445984] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"100.314303ms","start":"2026-02-20T18:32:03.631627Z","end":"2026-02-20T18:32:03.731941Z","steps":["trace[1048445984] 'process raft request' (duration: 100.092927ms)"],"step_count":1}