{"level":"warn","ts":"2026-02-25T02:15:19.284001Z","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-25T02:15:19.284097Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-25T02:15:19.284171Z","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-25T02:15:19.284187Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-25T02:15:19.284206Z","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-25T02:15:19.285031Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-02-25T02:15:19.285125Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-25T02:15:19.290125Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.759498ms"} {"level":"info","ts":"2026-02-25T02:15:19.301375Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-02-25T02:15:19.301525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:15:19.301564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-02-25T02:15:19.301593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:15:19.301614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-02-25T02:15:19.301678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-02-25T02:15:19.309309Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:15:19.310595Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:15:19.311752Z","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-25T02:15:19.313881Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:15:19.31415Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:15:19.314291Z","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-25T02:15:19.316928Z","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-25T02:15:19.316946Z","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-25T02:15:19.318746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-02-25T02:15:19.319238Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-02-25T02:15:19.320682Z","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-25T02:15:19.320939Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-25T02:15:19.320959Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-02-25T02:15:19.321183Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:15:19.320891Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:15:20.002109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:15:20.002184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:15:20.002208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-02-25T02:15:20.002229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:15:20.00224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-25T02:15:20.002275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-02-25T02:15:20.00229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-02-25T02:15:20.003382Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:15:20.003432Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:15:20.00391Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:15:20.004061Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:15:20.004465Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:15:20.004532Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:15:20.007974Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:15:20.008312Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:15:20.008462Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:15:20.008525Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:15:20.008798Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"warn","ts":"2026-02-25T02:15:24.077987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.070005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:24.078117Z","caller":"traceutil/trace.go:171","msg":"trace[220726796] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"220.293354ms","start":"2026-02-25T02:15:23.857801Z","end":"2026-02-25T02:15:24.078094Z","steps":["trace[220726796] 'process raft request' (duration: 38.637444ms)","trace[220726796] 'compare' (duration: 180.980712ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:24.440907Z","caller":"traceutil/trace.go:171","msg":"trace[1615713794] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"183.110109ms","start":"2026-02-25T02:15:24.257733Z","end":"2026-02-25T02:15:24.440843Z","steps":["trace[1615713794] 'process raft request' (duration: 93.954304ms)","trace[1615713794] 'compare' (duration: 88.900279ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:24.440931Z","caller":"traceutil/trace.go:171","msg":"trace[1499480829] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"144.28231ms","start":"2026-02-25T02:15:24.296624Z","end":"2026-02-25T02:15:24.440907Z","steps":["trace[1499480829] 'process raft request' (duration: 144.082215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:24.700218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.381474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:24.700433Z","caller":"traceutil/trace.go:171","msg":"trace[1742552924] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"204.359459ms","start":"2026-02-25T02:15:24.496056Z","end":"2026-02-25T02:15:24.700416Z","steps":["trace[1742552924] 'process raft request' (duration: 204.322398ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:24.700735Z","caller":"traceutil/trace.go:171","msg":"trace[389670519] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"230.836417ms","start":"2026-02-25T02:15:24.469875Z","end":"2026-02-25T02:15:24.700711Z","steps":["trace[389670519] 'process raft request' (duration: 119.891638ms)","trace[389670519] 'compare' (duration: 110.222209ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:24.70076Z","caller":"traceutil/trace.go:171","msg":"trace[1825686243] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"214.528711ms","start":"2026-02-25T02:15:24.4862Z","end":"2026-02-25T02:15:24.700729Z","steps":["trace[1825686243] 'process raft request' (duration: 214.12317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:24.934635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.830613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:24.934745Z","caller":"traceutil/trace.go:171","msg":"trace[504895792] linearizableReadLoop","detail":"{readStateIndex:230; appliedIndex:228; }","duration":"208.787247ms","start":"2026-02-25T02:15:24.725947Z","end":"2026-02-25T02:15:24.934735Z","steps":["trace[504895792] 'read index received' (duration: 93.82244ms)","trace[504895792] 'applied index is now lower than readState.Index' (duration: 114.964067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:24.934824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.855889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:24.934842Z","caller":"traceutil/trace.go:171","msg":"trace[82263789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:224; }","duration":"208.939241ms","start":"2026-02-25T02:15:24.725897Z","end":"2026-02-25T02:15:24.934837Z","steps":["trace[82263789] 'agreement among raft nodes before linearized reading' (duration: 208.86796ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:24.934821Z","caller":"traceutil/trace.go:171","msg":"trace[2013760793] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"230.456116ms","start":"2026-02-25T02:15:24.704304Z","end":"2026-02-25T02:15:24.934761Z","steps":["trace[2013760793] 'process raft request' (duration: 115.449949ms)","trace[2013760793] 'compare' (duration: 114.71793ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:24.934838Z","caller":"traceutil/trace.go:171","msg":"trace[93374711] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"229.883131ms","start":"2026-02-25T02:15:24.704914Z","end":"2026-02-25T02:15:24.934797Z","steps":["trace[93374711] 'process raft request' (duration: 229.789849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.153131Z","caller":"traceutil/trace.go:171","msg":"trace[2100636539] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"182.968466ms","start":"2026-02-25T02:15:24.970134Z","end":"2026-02-25T02:15:25.153102Z","steps":["trace[2100636539] 'process raft request' (duration: 122.020985ms)","trace[2100636539] 'compare' (duration: 60.790676ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:25.15327Z","caller":"traceutil/trace.go:171","msg":"trace[628778989] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"139.523074ms","start":"2026-02-25T02:15:25.013732Z","end":"2026-02-25T02:15:25.153255Z","steps":["trace[628778989] 'process raft request' (duration: 139.466632ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.153304Z","caller":"traceutil/trace.go:171","msg":"trace[1439579955] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"143.950132ms","start":"2026-02-25T02:15:25.009304Z","end":"2026-02-25T02:15:25.153254Z","steps":["trace[1439579955] 'read index received' (duration: 82.827076ms)","trace[1439579955] 'applied index is now lower than readState.Index' (duration: 61.117175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:25.153473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.133426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:25.153454Z","caller":"traceutil/trace.go:171","msg":"trace[752625144] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"140.039827ms","start":"2026-02-25T02:15:25.013293Z","end":"2026-02-25T02:15:25.153332Z","steps":["trace[752625144] 'process raft request' (duration: 139.830311ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.153519Z","caller":"traceutil/trace.go:171","msg":"trace[1012239244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:231; }","duration":"184.203588ms","start":"2026-02-25T02:15:24.969302Z","end":"2026-02-25T02:15:25.153506Z","steps":["trace[1012239244] 'agreement among raft nodes before linearized reading' (duration: 184.090055ms)"],"step_count":1} 2026/02/25 02:15:25 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:15:25.532334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.62908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:25.53246Z","caller":"traceutil/trace.go:171","msg":"trace[1164465257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:243; }","duration":"144.803714ms","start":"2026-02-25T02:15:25.387627Z","end":"2026-02-25T02:15:25.532431Z","steps":["trace[1164465257] 'range keys from in-memory index tree' (duration: 144.513167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.532512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.379881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-25T02:15:25.532585Z","caller":"traceutil/trace.go:171","msg":"trace[1257917001] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:243; }","duration":"151.528954ms","start":"2026-02-25T02:15:25.381037Z","end":"2026-02-25T02:15:25.532566Z","steps":["trace[1257917001] 'range keys from in-memory index tree' (duration: 151.287238ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.567738Z","caller":"traceutil/trace.go:171","msg":"trace[49341381] linearizableReadLoop","detail":"{readStateIndex:250; appliedIndex:249; }","duration":"178.211278ms","start":"2026-02-25T02:15:25.389482Z","end":"2026-02-25T02:15:25.567693Z","steps":["trace[49341381] 'read index received' (duration: 178.015063ms)","trace[49341381] 'applied index is now lower than readState.Index' (duration: 194.655µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:25.567832Z","caller":"traceutil/trace.go:171","msg":"trace[1999467282] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"179.293617ms","start":"2026-02-25T02:15:25.388495Z","end":"2026-02-25T02:15:25.567789Z","steps":["trace[1999467282] 'process raft request' (duration: 178.982349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.568172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.545997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-25T02:15:25.568239Z","caller":"traceutil/trace.go:171","msg":"trace[904904344] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:244; }","duration":"178.752533ms","start":"2026-02-25T02:15:25.389464Z","end":"2026-02-25T02:15:25.568216Z","steps":["trace[904904344] 'agreement among raft nodes before linearized reading' (duration: 178.425044ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.598702Z","caller":"traceutil/trace.go:171","msg":"trace[1987028318] transaction","detail":"{read_only:false; number_of_response:0; response_revision:245; }","duration":"116.529678ms","start":"2026-02-25T02:15:25.482116Z","end":"2026-02-25T02:15:25.598646Z","steps":["trace[1987028318] 'process raft request' (duration: 116.388845ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.598967Z","caller":"traceutil/trace.go:171","msg":"trace[1436299337] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"209.209088ms","start":"2026-02-25T02:15:25.389681Z","end":"2026-02-25T02:15:25.59889Z","steps":["trace[1436299337] 'process raft request' (duration: 208.681784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.599027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.930201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:25.59914Z","caller":"traceutil/trace.go:171","msg":"trace[267760721] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:249; }","duration":"209.086005ms","start":"2026-02-25T02:15:25.390031Z","end":"2026-02-25T02:15:25.599117Z","steps":["trace[267760721] 'agreement among raft nodes before linearized reading' (duration: 208.90347ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.705172Z","caller":"traceutil/trace.go:171","msg":"trace[965864859] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"104.504236ms","start":"2026-02-25T02:15:25.600621Z","end":"2026-02-25T02:15:25.705125Z","steps":["trace[965864859] 'process raft request' (duration: 53.81304ms)","trace[965864859] 'compare' (duration: 50.511101ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:25.705223Z","caller":"traceutil/trace.go:171","msg":"trace[506787961] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"100.272612ms","start":"2026-02-25T02:15:25.604935Z","end":"2026-02-25T02:15:25.705207Z","steps":["trace[506787961] 'process raft request' (duration: 100.236341ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.705222Z","caller":"traceutil/trace.go:171","msg":"trace[455304948] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:258; }","duration":"104.548157ms","start":"2026-02-25T02:15:25.600652Z","end":"2026-02-25T02:15:25.7052Z","steps":["trace[455304948] 'read index received' (duration: 53.795849ms)","trace[455304948] 'applied index is now lower than readState.Index' (duration: 50.747718ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:25.705272Z","caller":"traceutil/trace.go:171","msg":"trace[369081646] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"101.68467ms","start":"2026-02-25T02:15:25.603558Z","end":"2026-02-25T02:15:25.705243Z","steps":["trace[369081646] 'process raft request' (duration: 101.551797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.705443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.778543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-25T02:15:25.705473Z","caller":"traceutil/trace.go:171","msg":"trace[1714787418] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:252; }","duration":"104.854165ms","start":"2026-02-25T02:15:25.600611Z","end":"2026-02-25T02:15:25.705465Z","steps":["trace[1714787418] 'agreement among raft nodes before linearized reading' (duration: 104.700861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.705542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.108212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3711"} {"level":"warn","ts":"2026-02-25T02:15:25.705605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.735952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T02:15:25.705623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.576838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:25.705651Z","caller":"traceutil/trace.go:171","msg":"trace[672980834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:252; }","duration":"101.812934ms","start":"2026-02-25T02:15:25.603821Z","end":"2026-02-25T02:15:25.705634Z","steps":["trace[672980834] 'agreement among raft nodes before linearized reading' (duration: 101.697141ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.705653Z","caller":"traceutil/trace.go:171","msg":"trace[407156227] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:252; }","duration":"101.619279ms","start":"2026-02-25T02:15:25.604024Z","end":"2026-02-25T02:15:25.705643Z","steps":["trace[407156227] 'agreement among raft nodes before linearized reading' (duration: 101.568008ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.705651Z","caller":"traceutil/trace.go:171","msg":"trace[939270511] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:252; }","duration":"102.276127ms","start":"2026-02-25T02:15:25.60335Z","end":"2026-02-25T02:15:25.705626Z","steps":["trace[939270511] 'agreement among raft nodes before linearized reading' (duration: 102.061751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:25.705801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.062201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3711"} {"level":"info","ts":"2026-02-25T02:15:25.705858Z","caller":"traceutil/trace.go:171","msg":"trace[1569204281] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:252; }","duration":"102.173313ms","start":"2026-02-25T02:15:25.603671Z","end":"2026-02-25T02:15:25.705844Z","steps":["trace[1569204281] 'agreement among raft nodes before linearized reading' (duration: 101.966298ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.906652Z","caller":"traceutil/trace.go:171","msg":"trace[828438252] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:261; }","duration":"197.759752ms","start":"2026-02-25T02:15:25.708861Z","end":"2026-02-25T02:15:25.906621Z","steps":["trace[828438252] 'read index received' (duration: 169.753982ms)","trace[828438252] 'applied index is now lower than readState.Index' (duration: 28.00455ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:25.906704Z","caller":"traceutil/trace.go:171","msg":"trace[1476980596] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"198.281345ms","start":"2026-02-25T02:15:25.708369Z","end":"2026-02-25T02:15:25.90665Z","steps":["trace[1476980596] 'process raft request' (duration: 170.247715ms)","trace[1476980596] 'compare' (duration: 27.767363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:25.906849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.967547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T02:15:25.906914Z","caller":"traceutil/trace.go:171","msg":"trace[1235011592] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:253; }","duration":"198.09075ms","start":"2026-02-25T02:15:25.708808Z","end":"2026-02-25T02:15:25.906898Z","steps":["trace[1235011592] 'agreement among raft nodes before linearized reading' (duration: 197.908855ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.96746Z","caller":"traceutil/trace.go:171","msg":"trace[295589944] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"256.734249ms","start":"2026-02-25T02:15:25.710706Z","end":"2026-02-25T02:15:25.96744Z","steps":["trace[295589944] 'process raft request' (duration: 256.589835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.96778Z","caller":"traceutil/trace.go:171","msg":"trace[1450296895] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"255.815455ms","start":"2026-02-25T02:15:25.711925Z","end":"2026-02-25T02:15:25.96774Z","steps":["trace[1450296895] 'process raft request' (duration: 255.476166ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.967863Z","caller":"traceutil/trace.go:171","msg":"trace[2072514868] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"248.370355ms","start":"2026-02-25T02:15:25.719474Z","end":"2026-02-25T02:15:25.967844Z","steps":["trace[2072514868] 'process raft request' (duration: 248.284223ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:25.967964Z","caller":"traceutil/trace.go:171","msg":"trace[1224264808] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"255.438744ms","start":"2026-02-25T02:15:25.712506Z","end":"2026-02-25T02:15:25.967945Z","steps":["trace[1224264808] 'process raft request' (duration: 255.129716ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.036556Z","caller":"traceutil/trace.go:171","msg":"trace[1588742755] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"124.494782ms","start":"2026-02-25T02:15:25.912041Z","end":"2026-02-25T02:15:26.036536Z","steps":["trace[1588742755] 'process raft request' (duration: 124.45165ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.036612Z","caller":"traceutil/trace.go:171","msg":"trace[1759126690] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"125.694643ms","start":"2026-02-25T02:15:25.910894Z","end":"2026-02-25T02:15:26.036589Z","steps":["trace[1759126690] 'process raft request' (duration: 125.489268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.339173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.640075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:26.339368Z","caller":"traceutil/trace.go:171","msg":"trace[1354429294] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:272; }","duration":"223.060838ms","start":"2026-02-25T02:15:26.11629Z","end":"2026-02-25T02:15:26.339351Z","steps":["trace[1354429294] 'read index received' (duration: 70.199038ms)","trace[1354429294] 'applied index is now lower than readState.Index' (duration: 152.8607ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:26.339459Z","caller":"traceutil/trace.go:171","msg":"trace[792868490] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"234.786332ms","start":"2026-02-25T02:15:26.104631Z","end":"2026-02-25T02:15:26.339417Z","steps":["trace[792868490] 'process raft request' (duration: 234.656008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:26.339536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.245903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:26.33961Z","caller":"traceutil/trace.go:171","msg":"trace[1247994510] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:265; }","duration":"223.319205ms","start":"2026-02-25T02:15:26.116252Z","end":"2026-02-25T02:15:26.339572Z","steps":["trace[1247994510] 'agreement among raft nodes before linearized reading' (duration: 223.171481ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.339601Z","caller":"traceutil/trace.go:171","msg":"trace[14129094] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"234.893615ms","start":"2026-02-25T02:15:26.104565Z","end":"2026-02-25T02:15:26.339458Z","steps":["trace[14129094] 'process raft request' (duration: 81.879941ms)","trace[14129094] 'compare' (duration: 152.48825ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:26.537858Z","caller":"traceutil/trace.go:171","msg":"trace[1804854540] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:276; }","duration":"151.34914ms","start":"2026-02-25T02:15:26.386488Z","end":"2026-02-25T02:15:26.537837Z","steps":["trace[1804854540] 'read index received' (duration: 84.381828ms)","trace[1804854540] 'applied index is now lower than readState.Index' (duration: 66.966502ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:26.538079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.571536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:26.538119Z","caller":"traceutil/trace.go:171","msg":"trace[2076172556] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:268; }","duration":"151.660218ms","start":"2026-02-25T02:15:26.386445Z","end":"2026-02-25T02:15:26.538105Z","steps":["trace[2076172556] 'agreement among raft nodes before linearized reading' (duration: 151.518775ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.537943Z","caller":"traceutil/trace.go:171","msg":"trace[1005904031] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"155.034898ms","start":"2026-02-25T02:15:26.382878Z","end":"2026-02-25T02:15:26.537913Z","steps":["trace[1005904031] 'process raft request' (duration: 87.909802ms)","trace[1005904031] 'compare' (duration: 66.935751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:26.539088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.722227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-25T02:15:26.539157Z","caller":"traceutil/trace.go:171","msg":"trace[415294908] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:268; }","duration":"118.84021ms","start":"2026-02-25T02:15:26.420302Z","end":"2026-02-25T02:15:26.539142Z","steps":["trace[415294908] 'agreement among raft nodes before linearized reading' (duration: 118.723687ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.539334Z","caller":"traceutil/trace.go:171","msg":"trace[1652602218] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"117.804382ms","start":"2026-02-25T02:15:26.421523Z","end":"2026-02-25T02:15:26.539327Z","steps":["trace[1652602218] 'process raft request' (duration: 117.394461ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.539439Z","caller":"traceutil/trace.go:171","msg":"trace[2003148652] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"115.14082ms","start":"2026-02-25T02:15:26.424276Z","end":"2026-02-25T02:15:26.539417Z","steps":["trace[2003148652] 'process raft request' (duration: 114.729739ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.539598Z","caller":"traceutil/trace.go:171","msg":"trace[819732477] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"117.904555ms","start":"2026-02-25T02:15:26.421687Z","end":"2026-02-25T02:15:26.539592Z","steps":["trace[819732477] 'process raft request' (duration: 117.287039ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.539782Z","caller":"traceutil/trace.go:171","msg":"trace[283659595] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"117.997218ms","start":"2026-02-25T02:15:26.421737Z","end":"2026-02-25T02:15:26.539734Z","steps":["trace[283659595] 'process raft request' (duration: 117.254638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:26.70845Z","caller":"traceutil/trace.go:171","msg":"trace[902032220] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"133.916323ms","start":"2026-02-25T02:15:26.574499Z","end":"2026-02-25T02:15:26.708415Z","steps":["trace[902032220] 'process raft request' (duration: 111.658128ms)","trace[902032220] 'compare' (duration: 21.996158ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:27.21987Z","caller":"traceutil/trace.go:171","msg":"trace[1651848893] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"117.016151ms","start":"2026-02-25T02:15:27.102834Z","end":"2026-02-25T02:15:27.21985Z","steps":["trace[1651848893] 'read index received' (duration: 77.444722ms)","trace[1651848893] 'applied index is now lower than readState.Index' (duration: 39.570829ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:27.220031Z","caller":"traceutil/trace.go:171","msg":"trace[2043576989] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"174.633652ms","start":"2026-02-25T02:15:27.045347Z","end":"2026-02-25T02:15:27.21998Z","steps":["trace[2043576989] 'process raft request' (duration: 134.94354ms)","trace[2043576989] 'compare' (duration: 39.472807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:27.220283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.390782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T02:15:27.220323Z","caller":"traceutil/trace.go:171","msg":"trace[1934108315] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:283; }","duration":"117.563136ms","start":"2026-02-25T02:15:27.102749Z","end":"2026-02-25T02:15:27.220312Z","steps":["trace[1934108315] 'agreement among raft nodes before linearized reading' (duration: 117.206407ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:27.354444Z","caller":"traceutil/trace.go:171","msg":"trace[1074758803] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"117.745361ms","start":"2026-02-25T02:15:27.236675Z","end":"2026-02-25T02:15:27.35442Z","steps":["trace[1074758803] 'process raft request' (duration: 117.690819ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:27.354497Z","caller":"traceutil/trace.go:171","msg":"trace[1696895645] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"124.499992ms","start":"2026-02-25T02:15:27.229955Z","end":"2026-02-25T02:15:27.354455Z","steps":["trace[1696895645] 'process raft request' (duration: 97.066777ms)","trace[1696895645] 'compare' (duration: 27.177098ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:27.545462Z","caller":"traceutil/trace.go:171","msg":"trace[1239858156] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"121.321276ms","start":"2026-02-25T02:15:27.424065Z","end":"2026-02-25T02:15:27.545386Z","steps":["trace[1239858156] 'process raft request' (duration: 68.00772ms)","trace[1239858156] 'compare' (duration: 53.039669ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:28.34807Z","caller":"traceutil/trace.go:171","msg":"trace[1340250931] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:305; }","duration":"375.644241ms","start":"2026-02-25T02:15:27.972384Z","end":"2026-02-25T02:15:28.348028Z","steps":["trace[1340250931] 'read index received' (duration: 373.257607ms)","trace[1340250931] 'applied index is now lower than readState.Index' (duration: 2.385054ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:28.348328Z","caller":"traceutil/trace.go:171","msg":"trace[697228202] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"533.624428ms","start":"2026-02-25T02:15:27.814607Z","end":"2026-02-25T02:15:28.348231Z","steps":["trace[697228202] 'process raft request' (duration: 530.996958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:28.348371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.954729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T02:15:28.348427Z","caller":"traceutil/trace.go:171","msg":"trace[1930726254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:293; }","duration":"376.074102ms","start":"2026-02-25T02:15:27.972336Z","end":"2026-02-25T02:15:28.34841Z","steps":["trace[1930726254] 'agreement among raft nodes before linearized reading' (duration: 375.894657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:28.34847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:27.972317Z","time spent":"376.144385ms","remote":"127.0.0.1:42374","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-25T02:15:28.348539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:27.814591Z","time spent":"533.828363ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:15:29.692614Z","caller":"traceutil/trace.go:171","msg":"trace[592436818] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"176.849482ms","start":"2026-02-25T02:15:29.515737Z","end":"2026-02-25T02:15:29.692586Z","steps":["trace[592436818] 'process raft request' (duration: 93.0855ms)","trace[592436818] 'compare' (duration: 83.559466ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:29.692936Z","caller":"traceutil/trace.go:171","msg":"trace[1527043530] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:322; }","duration":"127.231354ms","start":"2026-02-25T02:15:29.565671Z","end":"2026-02-25T02:15:29.692903Z","steps":["trace[1527043530] 'read index received' (duration: 43.176685ms)","trace[1527043530] 'applied index is now lower than readState.Index' (duration: 84.053039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:29.693254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.561643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-25T02:15:29.693338Z","caller":"traceutil/trace.go:171","msg":"trace[1157959614] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:311; }","duration":"127.702117ms","start":"2026-02-25T02:15:29.565614Z","end":"2026-02-25T02:15:29.693316Z","steps":["trace[1157959614] 'agreement among raft nodes before linearized reading' (duration: 127.558463ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:29.693483Z","caller":"traceutil/trace.go:171","msg":"trace[1435103425] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"177.097768ms","start":"2026-02-25T02:15:29.516369Z","end":"2026-02-25T02:15:29.693466Z","steps":["trace[1435103425] 'process raft request' (duration: 176.155633ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:29.817746Z","caller":"traceutil/trace.go:171","msg":"trace[238267341] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:324; }","duration":"103.602082ms","start":"2026-02-25T02:15:29.714116Z","end":"2026-02-25T02:15:29.817718Z","steps":["trace[238267341] 'read index received' (duration: 732.53µs)","trace[238267341] 'applied index is now lower than readState.Index' (duration: 102.868442ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:29.817949Z","caller":"traceutil/trace.go:171","msg":"trace[375577960] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"108.151374ms","start":"2026-02-25T02:15:29.709716Z","end":"2026-02-25T02:15:29.817867Z","steps":["trace[375577960] 'process raft request' (duration: 94.043137ms)","trace[375577960] 'compare' (duration: 13.822219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:29.817957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.812557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T02:15:29.818205Z","caller":"traceutil/trace.go:171","msg":"trace[700664975] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:313; }","duration":"104.061325ms","start":"2026-02-25T02:15:29.714089Z","end":"2026-02-25T02:15:29.818151Z","steps":["trace[700664975] 'agreement among raft nodes before linearized reading' (duration: 103.792237ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:29.980349Z","caller":"traceutil/trace.go:171","msg":"trace[384682859] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:326; }","duration":"115.653415ms","start":"2026-02-25T02:15:29.864658Z","end":"2026-02-25T02:15:29.980311Z","steps":["trace[384682859] 'read index received' (duration: 34.75714ms)","trace[384682859] 'applied index is now lower than readState.Index' (duration: 80.894485ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:29.98037Z","caller":"traceutil/trace.go:171","msg":"trace[1556971761] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"152.654725ms","start":"2026-02-25T02:15:29.827677Z","end":"2026-02-25T02:15:29.980332Z","steps":["trace[1556971761] 'process raft request' (duration: 102.700319ms)","trace[1556971761] 'compare' (duration: 49.73656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:29.980597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.918081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T02:15:29.980659Z","caller":"traceutil/trace.go:171","msg":"trace[682217346] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:315; }","duration":"116.031834ms","start":"2026-02-25T02:15:29.864612Z","end":"2026-02-25T02:15:29.980643Z","steps":["trace[682217346] 'agreement among raft nodes before linearized reading' (duration: 115.837859ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.250341Z","caller":"traceutil/trace.go:171","msg":"trace[1327298547] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"135.542027ms","start":"2026-02-25T02:15:30.114773Z","end":"2026-02-25T02:15:30.250315Z","steps":["trace[1327298547] 'read index received' (duration: 72.0871ms)","trace[1327298547] 'applied index is now lower than readState.Index' (duration: 63.450817ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:30.250371Z","caller":"traceutil/trace.go:171","msg":"trace[174733121] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"177.832178ms","start":"2026-02-25T02:15:30.072504Z","end":"2026-02-25T02:15:30.250336Z","steps":["trace[174733121] 'process raft request' (duration: 114.449542ms)","trace[174733121] 'compare' (duration: 63.130429ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:30.25052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.721012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-25T02:15:30.250587Z","caller":"traceutil/trace.go:171","msg":"trace[1961348126] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:318; }","duration":"135.826644ms","start":"2026-02-25T02:15:30.114744Z","end":"2026-02-25T02:15:30.250571Z","steps":["trace[1961348126] 'agreement among raft nodes before linearized reading' (duration: 135.65519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.473057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.820506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:30.473369Z","caller":"traceutil/trace.go:171","msg":"trace[1452619378] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"205.414787ms","start":"2026-02-25T02:15:30.267935Z","end":"2026-02-25T02:15:30.47335Z","steps":["trace[1452619378] 'process raft request' (duration: 205.359745ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.473549Z","caller":"traceutil/trace.go:171","msg":"trace[1149781280] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"213.434641ms","start":"2026-02-25T02:15:30.260021Z","end":"2026-02-25T02:15:30.473456Z","steps":["trace[1149781280] 'process raft request' (duration: 213.150194ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.473643Z","caller":"traceutil/trace.go:171","msg":"trace[620431930] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:331; }","duration":"209.703492ms","start":"2026-02-25T02:15:30.263919Z","end":"2026-02-25T02:15:30.473623Z","steps":["trace[620431930] 'read index received' (duration: 1.238443ms)","trace[620431930] 'applied index is now lower than readState.Index' (duration: 208.462269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:30.473909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.974999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-25T02:15:30.47391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.938632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T02:15:30.473974Z","caller":"traceutil/trace.go:171","msg":"trace[314482531] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:322; }","duration":"210.063401ms","start":"2026-02-25T02:15:30.263893Z","end":"2026-02-25T02:15:30.473957Z","steps":["trace[314482531] 'agreement among raft nodes before linearized reading' (duration: 209.882946ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.473991Z","caller":"traceutil/trace.go:171","msg":"trace[1701419652] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:322; }","duration":"110.046635ms","start":"2026-02-25T02:15:30.363924Z","end":"2026-02-25T02:15:30.473971Z","steps":["trace[1701419652] 'agreement among raft nodes before linearized reading' (duration: 109.879201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.473999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.143152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T02:15:30.474047Z","caller":"traceutil/trace.go:171","msg":"trace[1038760732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:322; }","duration":"115.222963ms","start":"2026-02-25T02:15:30.358808Z","end":"2026-02-25T02:15:30.474031Z","steps":["trace[1038760732] 'agreement among raft nodes before linearized reading' (duration: 115.139381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.473907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.316023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T02:15:30.474117Z","caller":"traceutil/trace.go:171","msg":"trace[340355728] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:322; }","duration":"159.58015ms","start":"2026-02-25T02:15:30.314523Z","end":"2026-02-25T02:15:30.474103Z","steps":["trace[340355728] 'agreement among raft nodes before linearized reading' (duration: 159.271931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.474343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.481541ms","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-25T02:15:30.474375Z","caller":"traceutil/trace.go:171","msg":"trace[58929146] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"216.593156ms","start":"2026-02-25T02:15:30.257529Z","end":"2026-02-25T02:15:30.474122Z","steps":["trace[58929146] 'process raft request' (duration: 92.622869ms)","trace[58929146] 'compare' (duration: 122.548729ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:30.474425Z","caller":"traceutil/trace.go:171","msg":"trace[1352396864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:322; }","duration":"115.601504ms","start":"2026-02-25T02:15:30.358801Z","end":"2026-02-25T02:15:30.474402Z","steps":["trace[1352396864] 'agreement among raft nodes before linearized reading' (duration: 115.44626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.721129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.896815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:30.721212Z","caller":"traceutil/trace.go:171","msg":"trace[44825501] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"244.091221ms","start":"2026-02-25T02:15:30.477105Z","end":"2026-02-25T02:15:30.721196Z","steps":["trace[44825501] 'process raft request' (duration: 117.082783ms)","trace[44825501] 'compare' (duration: 126.799013ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:30.749593Z","caller":"traceutil/trace.go:171","msg":"trace[208435465] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"271.539915ms","start":"2026-02-25T02:15:30.478023Z","end":"2026-02-25T02:15:30.749563Z","steps":["trace[208435465] 'process raft request' (duration: 271.279678ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.7496Z","caller":"traceutil/trace.go:171","msg":"trace[1072862498] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"270.654151ms","start":"2026-02-25T02:15:30.478915Z","end":"2026-02-25T02:15:30.749569Z","steps":["trace[1072862498] 'process raft request' (duration: 270.493057ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.749693Z","caller":"traceutil/trace.go:171","msg":"trace[300317922] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"269.987644ms","start":"2026-02-25T02:15:30.479692Z","end":"2026-02-25T02:15:30.74968Z","steps":["trace[300317922] 'process raft request' (duration: 269.758948ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.749685Z","caller":"traceutil/trace.go:171","msg":"trace[1759207799] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"271.244468ms","start":"2026-02-25T02:15:30.478413Z","end":"2026-02-25T02:15:30.749658Z","steps":["trace[1759207799] 'process raft request' (duration: 270.9551ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.749813Z","caller":"traceutil/trace.go:171","msg":"trace[1032155140] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:335; }","duration":"222.822272ms","start":"2026-02-25T02:15:30.526963Z","end":"2026-02-25T02:15:30.749785Z","steps":["trace[1032155140] 'read index received' (duration: 67.26177ms)","trace[1032155140] 'applied index is now lower than readState.Index' (duration: 155.558612ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:30.74991Z","caller":"traceutil/trace.go:171","msg":"trace[891421302] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"267.123478ms","start":"2026-02-25T02:15:30.482756Z","end":"2026-02-25T02:15:30.74988Z","steps":["trace[891421302] 'process raft request' (duration: 266.754958ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.749997Z","caller":"traceutil/trace.go:171","msg":"trace[422649481] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"266.138511ms","start":"2026-02-25T02:15:30.4836Z","end":"2026-02-25T02:15:30.749738Z","steps":["trace[422649481] 'process raft request' (duration: 265.946976ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.750002Z","caller":"traceutil/trace.go:171","msg":"trace[216410351] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"265.037752ms","start":"2026-02-25T02:15:30.484807Z","end":"2026-02-25T02:15:30.749845Z","steps":["trace[216410351] 'process raft request' (duration: 264.769724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.75012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.1409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"warn","ts":"2026-02-25T02:15:30.750094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.487687ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:30.75019Z","caller":"traceutil/trace.go:171","msg":"trace[1946797983] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:332; }","duration":"150.59924ms","start":"2026-02-25T02:15:30.59958Z","end":"2026-02-25T02:15:30.750179Z","steps":["trace[1946797983] 'agreement among raft nodes before linearized reading' (duration: 150.471796ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.750184Z","caller":"traceutil/trace.go:171","msg":"trace[1802135290] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:332; }","duration":"223.246893ms","start":"2026-02-25T02:15:30.52692Z","end":"2026-02-25T02:15:30.750167Z","steps":["trace[1802135290] 'agreement among raft nodes before linearized reading' (duration: 223.11934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.750215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.793145ms","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-25T02:15:30.750193Z","caller":"traceutil/trace.go:171","msg":"trace[810970865] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"226.086899ms","start":"2026-02-25T02:15:30.524076Z","end":"2026-02-25T02:15:30.750163Z","steps":["trace[810970865] 'process raft request' (duration: 225.563675ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.750258Z","caller":"traceutil/trace.go:171","msg":"trace[513127917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:332; }","duration":"125.853667ms","start":"2026-02-25T02:15:30.62439Z","end":"2026-02-25T02:15:30.750244Z","steps":["trace[513127917] 'agreement among raft nodes before linearized reading' (duration: 125.789926ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.749842Z","caller":"traceutil/trace.go:171","msg":"trace[88315658] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"251.299124ms","start":"2026-02-25T02:15:30.498534Z","end":"2026-02-25T02:15:30.749833Z","steps":["trace[88315658] 'process raft request' (duration: 251.076988ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.901456Z","caller":"traceutil/trace.go:171","msg":"trace[189433041] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"146.344575ms","start":"2026-02-25T02:15:30.755066Z","end":"2026-02-25T02:15:30.901411Z","steps":["trace[189433041] 'process raft request' (duration: 99.269086ms)","trace[189433041] 'compare' (duration: 46.71202ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:30.901511Z","caller":"traceutil/trace.go:171","msg":"trace[1600606481] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"145.666278ms","start":"2026-02-25T02:15:30.7558Z","end":"2026-02-25T02:15:30.901466Z","steps":["trace[1600606481] 'process raft request' (duration: 145.487973ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.901667Z","caller":"traceutil/trace.go:171","msg":"trace[340192528] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:346; }","duration":"126.797082ms","start":"2026-02-25T02:15:30.774805Z","end":"2026-02-25T02:15:30.901602Z","steps":["trace[340192528] 'read index received' (duration: 79.444685ms)","trace[340192528] 'applied index is now lower than readState.Index' (duration: 47.351357ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:30.901819Z","caller":"traceutil/trace.go:171","msg":"trace[859649464] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"145.947765ms","start":"2026-02-25T02:15:30.75586Z","end":"2026-02-25T02:15:30.901808Z","steps":["trace[859649464] 'process raft request' (duration: 145.488983ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.902018Z","caller":"traceutil/trace.go:171","msg":"trace[506512588] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"143.803348ms","start":"2026-02-25T02:15:30.758195Z","end":"2026-02-25T02:15:30.901999Z","steps":["trace[506512588] 'process raft request' (duration: 143.258613ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.902053Z","caller":"traceutil/trace.go:171","msg":"trace[1593379885] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"135.807564ms","start":"2026-02-25T02:15:30.766216Z","end":"2026-02-25T02:15:30.902024Z","steps":["trace[1593379885] 'process raft request' (duration: 135.340301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.902136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.378068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T02:15:30.902123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.492567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-02-25T02:15:30.901843Z","caller":"traceutil/trace.go:171","msg":"trace[603414338] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"143.012406ms","start":"2026-02-25T02:15:30.758814Z","end":"2026-02-25T02:15:30.901826Z","steps":["trace[603414338] 'process raft request' (duration: 142.698478ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.902311Z","caller":"traceutil/trace.go:171","msg":"trace[1956918705] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:340; }","duration":"144.699861ms","start":"2026-02-25T02:15:30.757536Z","end":"2026-02-25T02:15:30.902236Z","steps":["trace[1956918705] 'agreement among raft nodes before linearized reading' (duration: 144.414334ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.902399Z","caller":"traceutil/trace.go:171","msg":"trace[2052837687] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"144.62104ms","start":"2026-02-25T02:15:30.757571Z","end":"2026-02-25T02:15:30.902192Z","steps":["trace[2052837687] 'process raft request' (duration: 143.821069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:30.902456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.128906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:2205"} {"level":"info","ts":"2026-02-25T02:15:30.902534Z","caller":"traceutil/trace.go:171","msg":"trace[779590061] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:340; }","duration":"144.24618ms","start":"2026-02-25T02:15:30.758269Z","end":"2026-02-25T02:15:30.902516Z","steps":["trace[779590061] 'agreement among raft nodes before linearized reading' (duration: 144.088946ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:30.902206Z","caller":"traceutil/trace.go:171","msg":"trace[1827949364] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:340; }","duration":"124.482221ms","start":"2026-02-25T02:15:30.77769Z","end":"2026-02-25T02:15:30.902173Z","steps":["trace[1827949364] 'agreement among raft nodes before linearized reading' (duration: 124.362538ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:33.794042Z","caller":"traceutil/trace.go:171","msg":"trace[2053521733] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"438.899196ms","start":"2026-02-25T02:15:33.355125Z","end":"2026-02-25T02:15:33.794024Z","steps":["trace[2053521733] 'process raft request' (duration: 438.787153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:33.794166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:33.355109Z","time spent":"438.996579ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:34.120273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.36695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-25T02:15:34.120425Z","caller":"traceutil/trace.go:171","msg":"trace[1268148631] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:371; }","duration":"153.573226ms","start":"2026-02-25T02:15:33.966833Z","end":"2026-02-25T02:15:34.120406Z","steps":["trace[1268148631] 'range keys from in-memory index tree' (duration: 153.266788ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:34.283648Z","caller":"traceutil/trace.go:171","msg":"trace[1820076842] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"156.149526ms","start":"2026-02-25T02:15:34.127479Z","end":"2026-02-25T02:15:34.283629Z","steps":["trace[1820076842] 'process raft request' (duration: 156.031003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:46.000595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033500694s","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-25T02:15:46.000695Z","caller":"traceutil/trace.go:171","msg":"trace[1572488957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:431; }","duration":"1.033648668s","start":"2026-02-25T02:15:44.967026Z","end":"2026-02-25T02:15:46.000674Z","steps":["trace[1572488957] 'range keys from in-memory index tree' (duration: 1.033356449s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:46.000746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:44.967006Z","time spent":"1.033727529s","remote":"127.0.0.1:42374","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-25T02:15:46.000732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.846445ms","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-25T02:15:46.001248Z","caller":"traceutil/trace.go:171","msg":"trace[383755436] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:431; }","duration":"338.431911ms","start":"2026-02-25T02:15:45.662785Z","end":"2026-02-25T02:15:46.001217Z","steps":["trace[383755436] 'range keys from in-memory index tree' (duration: 337.821033ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:49.575965Z","caller":"traceutil/trace.go:171","msg":"trace[1991058210] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"635.720134ms","start":"2026-02-25T02:15:48.940216Z","end":"2026-02-25T02:15:49.575936Z","steps":["trace[1991058210] 'process raft request' (duration: 613.379218ms)","trace[1991058210] 'compare' (duration: 22.156291ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:49.576111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:48.940194Z","time spent":"635.845606ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:52.73499Z","caller":"traceutil/trace.go:171","msg":"trace[522307357] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"160.812162ms","start":"2026-02-25T02:15:52.57415Z","end":"2026-02-25T02:15:52.734962Z","steps":["trace[522307357] 'process raft request' (duration: 160.490164ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:52.955737Z","caller":"traceutil/trace.go:171","msg":"trace[501685396] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"195.815532ms","start":"2026-02-25T02:15:52.759876Z","end":"2026-02-25T02:15:52.955691Z","steps":["trace[501685396] 'process raft request' (duration: 130.348856ms)","trace[501685396] 'compare' (duration: 65.309892ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:52.95577Z","caller":"traceutil/trace.go:171","msg":"trace[802396824] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"195.093822ms","start":"2026-02-25T02:15:52.760641Z","end":"2026-02-25T02:15:52.955735Z","steps":["trace[802396824] 'process raft request' (duration: 194.982579ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:53.156264Z","caller":"traceutil/trace.go:171","msg":"trace[1395859048] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"140.941853ms","start":"2026-02-25T02:15:53.015288Z","end":"2026-02-25T02:15:53.156229Z","steps":["trace[1395859048] 'process raft request' (duration: 42.39542ms)","trace[1395859048] 'compare' (duration: 98.04258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:53.156514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.360548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-25T02:15:53.156318Z","caller":"traceutil/trace.go:171","msg":"trace[1427189674] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:472; }","duration":"133.164932ms","start":"2026-02-25T02:15:53.023131Z","end":"2026-02-25T02:15:53.156296Z","steps":["trace[1427189674] 'read index received' (duration: 34.508206ms)","trace[1427189674] 'applied index is now lower than readState.Index' (duration: 98.655386ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:53.156584Z","caller":"traceutil/trace.go:171","msg":"trace[1585354335] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:455; }","duration":"133.482741ms","start":"2026-02-25T02:15:53.023085Z","end":"2026-02-25T02:15:53.156568Z","steps":["trace[1585354335] 'agreement among raft nodes before linearized reading' (duration: 133.276226ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:53.156374Z","caller":"traceutil/trace.go:171","msg":"trace[1324461755] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"140.757498ms","start":"2026-02-25T02:15:53.015571Z","end":"2026-02-25T02:15:53.156329Z","steps":["trace[1324461755] 'process raft request' (duration: 140.509111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:53.156708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.054535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:53.156772Z","caller":"traceutil/trace.go:171","msg":"trace[1565506013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"117.155368ms","start":"2026-02-25T02:15:53.039595Z","end":"2026-02-25T02:15:53.156751Z","steps":["trace[1565506013] 'agreement among raft nodes before linearized reading' (duration: 117.049625ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:53.548203Z","caller":"traceutil/trace.go:171","msg":"trace[98527226] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"100.26486ms","start":"2026-02-25T02:15:53.447916Z","end":"2026-02-25T02:15:53.54818Z","steps":["trace[98527226] 'read index received' (duration: 100.134637ms)","trace[98527226] 'applied index is now lower than readState.Index' (duration: 129.103µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:53.548244Z","caller":"traceutil/trace.go:171","msg":"trace[1442187064] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"146.165095ms","start":"2026-02-25T02:15:53.40205Z","end":"2026-02-25T02:15:53.548215Z","steps":["trace[1442187064] 'process raft request' (duration: 145.98519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:53.548377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.438495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:15:53.548418Z","caller":"traceutil/trace.go:171","msg":"trace[134772327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:460; }","duration":"100.530047ms","start":"2026-02-25T02:15:53.447873Z","end":"2026-02-25T02:15:53.548403Z","steps":["trace[134772327] 'agreement among raft nodes before linearized reading' (duration: 100.400564ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:53.920398Z","caller":"traceutil/trace.go:171","msg":"trace[1335191035] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"227.265684ms","start":"2026-02-25T02:15:53.693115Z","end":"2026-02-25T02:15:53.920381Z","steps":["trace[1335191035] 'process raft request' (duration: 227.234674ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:53.920508Z","caller":"traceutil/trace.go:171","msg":"trace[1951792546] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"229.342281ms","start":"2026-02-25T02:15:53.69111Z","end":"2026-02-25T02:15:53.920452Z","steps":["trace[1951792546] 'process raft request' (duration: 229.199207ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:53.920642Z","caller":"traceutil/trace.go:171","msg":"trace[545448938] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"228.601241ms","start":"2026-02-25T02:15:53.692036Z","end":"2026-02-25T02:15:53.920637Z","steps":["trace[545448938] 'process raft request' (duration: 228.295243ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:53.920813Z","caller":"traceutil/trace.go:171","msg":"trace[185698224] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"229.779293ms","start":"2026-02-25T02:15:53.69098Z","end":"2026-02-25T02:15:53.920759Z","steps":["trace[185698224] 'process raft request' (duration: 229.280709ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:53.92085Z","caller":"traceutil/trace.go:171","msg":"trace[659296765] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"234.064719ms","start":"2026-02-25T02:15:53.686685Z","end":"2026-02-25T02:15:53.920749Z","steps":["trace[659296765] 'process raft request' (duration: 226.606437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.221795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.204991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:54.222052Z","caller":"traceutil/trace.go:171","msg":"trace[1080173303] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"295.317691ms","start":"2026-02-25T02:15:53.926698Z","end":"2026-02-25T02:15:54.222016Z","steps":["trace[1080173303] 'process raft request' (duration: 295.197758ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:54.222131Z","caller":"traceutil/trace.go:171","msg":"trace[1694222129] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"295.391302ms","start":"2026-02-25T02:15:53.926723Z","end":"2026-02-25T02:15:54.222114Z","steps":["trace[1694222129] 'process raft request' (duration: 295.227938ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:54.222126Z","caller":"traceutil/trace.go:171","msg":"trace[88718680] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"296.186574ms","start":"2026-02-25T02:15:53.925916Z","end":"2026-02-25T02:15:54.222102Z","steps":["trace[88718680] 'process raft request' (duration: 144.594692ms)","trace[88718680] 'compare' (duration: 151.051667ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:54.222158Z","caller":"traceutil/trace.go:171","msg":"trace[778645040] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:489; }","duration":"295.334291ms","start":"2026-02-25T02:15:53.926803Z","end":"2026-02-25T02:15:54.222137Z","steps":["trace[778645040] 'read index received' (duration: 21.314448ms)","trace[778645040] 'applied index is now lower than readState.Index' (duration: 274.018653ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:54.222218Z","caller":"traceutil/trace.go:171","msg":"trace[1289642697] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"289.177904ms","start":"2026-02-25T02:15:53.93303Z","end":"2026-02-25T02:15:54.222208Z","steps":["trace[1289642697] 'process raft request' (duration: 289.02093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.22234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.528086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-25T02:15:54.222405Z","caller":"traceutil/trace.go:171","msg":"trace[1022613494] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:475; }","duration":"295.64014ms","start":"2026-02-25T02:15:53.926748Z","end":"2026-02-25T02:15:54.222388Z","steps":["trace[1022613494] 'agreement among raft nodes before linearized reading' (duration: 295.539767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.222468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.812668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T02:15:54.222482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.445827ms","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-25T02:15:54.222542Z","caller":"traceutil/trace.go:171","msg":"trace[1743542673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"134.53427ms","start":"2026-02-25T02:15:54.08799Z","end":"2026-02-25T02:15:54.222524Z","steps":["trace[1743542673] 'agreement among raft nodes before linearized reading' (duration: 134.418246ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:54.222503Z","caller":"traceutil/trace.go:171","msg":"trace[1541613682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"137.893431ms","start":"2026-02-25T02:15:54.0846Z","end":"2026-02-25T02:15:54.222493Z","steps":["trace[1541613682] 'agreement among raft nodes before linearized reading' (duration: 137.812449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:54.222692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.782062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-02-25T02:15:54.222767Z","caller":"traceutil/trace.go:171","msg":"trace[1265570443] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:475; }","duration":"219.892685ms","start":"2026-02-25T02:15:54.002853Z","end":"2026-02-25T02:15:54.222746Z","steps":["trace[1265570443] 'agreement among raft nodes before linearized reading' (duration: 219.519234ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:54.222935Z","caller":"traceutil/trace.go:171","msg":"trace[701829707] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"295.262749ms","start":"2026-02-25T02:15:53.927652Z","end":"2026-02-25T02:15:54.222915Z","steps":["trace[701829707] 'process raft request' (duration: 294.337494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.379603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.457588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:15:55.379889Z","caller":"traceutil/trace.go:171","msg":"trace[1707527786] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.152181214s","start":"2026-02-25T02:15:54.227676Z","end":"2026-02-25T02:15:55.379857Z","steps":["trace[1707527786] 'process raft request' (duration: 433.333455ms)","trace[1707527786] 'compare' (duration: 718.257583ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:55.379993Z","caller":"traceutil/trace.go:171","msg":"trace[547714127] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.151364571s","start":"2026-02-25T02:15:54.228618Z","end":"2026-02-25T02:15:55.379983Z","steps":["trace[547714127] 'process raft request' (duration: 1.151180026s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:55.380035Z","caller":"traceutil/trace.go:171","msg":"trace[464345575] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"1.152156473s","start":"2026-02-25T02:15:54.227856Z","end":"2026-02-25T02:15:55.380012Z","steps":["trace[464345575] 'read index received' (duration: 54.694854ms)","trace[464345575] 'applied index is now lower than readState.Index' (duration: 1.097460299s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:15:55.380078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.228588Z","time spent":"1.151433953s","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:55.380124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.227657Z","time spent":"1.152311017s","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:55.380306Z","caller":"traceutil/trace.go:171","msg":"trace[1012502154] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.150782206s","start":"2026-02-25T02:15:54.229516Z","end":"2026-02-25T02:15:55.380298Z","steps":["trace[1012502154] 'process raft request' (duration: 1.150369535s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.380351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.229498Z","time spent":"1.150831367s","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:55.380478Z","caller":"traceutil/trace.go:171","msg":"trace[1149581583] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.149522871s","start":"2026-02-25T02:15:54.230937Z","end":"2026-02-25T02:15:55.38046Z","steps":["trace[1149581583] 'process raft request' (duration: 1.149004387s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.380525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152672617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2n27j\" ","response":"range_response_count:1 size:14921"} {"level":"info","ts":"2026-02-25T02:15:55.380575Z","caller":"traceutil/trace.go:171","msg":"trace[1181925961] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2n27j; range_end:; response_count:1; response_revision:480; }","duration":"1.152723228s","start":"2026-02-25T02:15:54.22784Z","end":"2026-02-25T02:15:55.380563Z","steps":["trace[1181925961] 'agreement among raft nodes before linearized reading' (duration: 1.152617175s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.38054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.230922Z","time spent":"1.149592184s","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4595,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:15:55.380612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.227547Z","time spent":"1.153058357s","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14943,"request content":"key:\"/registry/pods/kube-system/cilium-2n27j\" "} {"level":"info","ts":"2026-02-25T02:15:55.401709Z","caller":"traceutil/trace.go:171","msg":"trace[717997890] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.115239702s","start":"2026-02-25T02:15:54.286445Z","end":"2026-02-25T02:15:55.401685Z","steps":["trace[717997890] 'process raft request' (duration: 1.115113949s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.401844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.286419Z","time spent":"1.115360716s","remote":"127.0.0.1:42132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:15:55.402408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.809923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-25T02:15:55.402473Z","caller":"traceutil/trace.go:171","msg":"trace[1381041276] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:481; }","duration":"967.930526ms","start":"2026-02-25T02:15:54.43453Z","end":"2026-02-25T02:15:55.40246Z","steps":["trace[1381041276] 'agreement among raft nodes before linearized reading' (duration: 967.786282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.402486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.357705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:15:55.402502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168823975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14309"} {"level":"warn","ts":"2026-02-25T02:15:55.402535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0723984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-02-25T02:15:55.40255Z","caller":"traceutil/trace.go:171","msg":"trace[731599506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"955.443127ms","start":"2026-02-25T02:15:54.447087Z","end":"2026-02-25T02:15:55.40253Z","steps":["trace[731599506] 'agreement among raft nodes before linearized reading' (duration: 955.275242ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:55.402564Z","caller":"traceutil/trace.go:171","msg":"trace[369851386] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:481; }","duration":"1.168925588s","start":"2026-02-25T02:15:54.23362Z","end":"2026-02-25T02:15:55.402546Z","steps":["trace[369851386] 'agreement among raft nodes before linearized reading' (duration: 1.168796405s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.402504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.434512Z","time spent":"967.985347ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":560,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-25T02:15:55.402602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.447076Z","time spent":"955.515069ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:15:55.402564Z","caller":"traceutil/trace.go:171","msg":"trace[1956496811] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:481; }","duration":"1.072477442s","start":"2026-02-25T02:15:54.330076Z","end":"2026-02-25T02:15:55.402554Z","steps":["trace[1956496811] 'agreement among raft nodes before linearized reading' (duration: 1.072307307s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.402689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.33005Z","time spent":"1.072631016s","remote":"127.0.0.1:42162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329958,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-25T02:15:55.402408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.981064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T02:15:55.402905Z","caller":"traceutil/trace.go:171","msg":"trace[1983836983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:481; }","duration":"388.5259ms","start":"2026-02-25T02:15:55.014362Z","end":"2026-02-25T02:15:55.402888Z","steps":["trace[1983836983] 'agreement among raft nodes before linearized reading' (duration: 387.986465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.402956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.014347Z","time spent":"388.594391ms","remote":"127.0.0.1:42374","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-25T02:15:55.402603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.233612Z","time spent":"1.16898166s","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14331,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-25T02:15:55.403245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070484407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-25T02:15:55.403399Z","caller":"traceutil/trace.go:171","msg":"trace[1162562394] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:481; }","duration":"1.070665393s","start":"2026-02-25T02:15:54.332706Z","end":"2026-02-25T02:15:55.403371Z","steps":["trace[1162562394] 'agreement among raft nodes before linearized reading' (duration: 1.069615024s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:55.403501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:54.332696Z","time spent":"1.070786756s","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":542,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-25T02:15:55.903288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100614431443231,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:15:56.127793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.587799ms","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-25T02:15:56.127978Z","caller":"traceutil/trace.go:171","msg":"trace[1950199834] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:481; }","duration":"465.781664ms","start":"2026-02-25T02:15:55.662162Z","end":"2026-02-25T02:15:56.127944Z","steps":["trace[1950199834] 'range keys from in-memory index tree' (duration: 465.572059ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:56.130074Z","caller":"traceutil/trace.go:171","msg":"trace[1511278275] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"733.924929ms","start":"2026-02-25T02:15:55.396125Z","end":"2026-02-25T02:15:56.13005Z","steps":["trace[1511278275] 'process raft request' (duration: 733.756484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:56.130289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.396096Z","time spent":"734.059143ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16641,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:56.13242Z","caller":"traceutil/trace.go:171","msg":"trace[1822680568] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:500; }","duration":"730.120555ms","start":"2026-02-25T02:15:55.402275Z","end":"2026-02-25T02:15:56.132396Z","steps":["trace[1822680568] 'read index received' (duration: 727.645658ms)","trace[1822680568] 'applied index is now lower than readState.Index' (duration: 2.473787ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:15:56.132493Z","caller":"traceutil/trace.go:171","msg":"trace[2136444843] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"725.441668ms","start":"2026-02-25T02:15:55.407028Z","end":"2026-02-25T02:15:56.132469Z","steps":["trace[2136444843] 'process raft request' (duration: 725.248403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:56.132592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.150023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4610"} {"level":"warn","ts":"2026-02-25T02:15:56.132635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.407011Z","time spent":"725.54228ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:15:56.132664Z","caller":"traceutil/trace.go:171","msg":"trace[1421180907] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:483; }","duration":"745.259216ms","start":"2026-02-25T02:15:55.387384Z","end":"2026-02-25T02:15:56.132643Z","steps":["trace[1421180907] 'agreement among raft nodes before linearized reading' (duration: 745.109172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:56.132704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.387364Z","time spent":"745.331147ms","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4632,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-25T02:15:56.132715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.257194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2n27j\" ","response":"range_response_count:1 size:16656"} {"level":"warn","ts":"2026-02-25T02:15:56.132707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.98507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14309"} {"level":"info","ts":"2026-02-25T02:15:56.132764Z","caller":"traceutil/trace.go:171","msg":"trace[440450914] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2n27j; range_end:; response_count:1; response_revision:483; }","duration":"728.329707ms","start":"2026-02-25T02:15:55.404418Z","end":"2026-02-25T02:15:56.132747Z","steps":["trace[440450914] 'agreement among raft nodes before linearized reading' (duration: 728.216804ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:15:56.132782Z","caller":"traceutil/trace.go:171","msg":"trace[1274377048] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:483; }","duration":"727.086953ms","start":"2026-02-25T02:15:55.405678Z","end":"2026-02-25T02:15:56.132765Z","steps":["trace[1274377048] 'agreement among raft nodes before linearized reading' (duration: 726.948699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:56.132812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.404398Z","time spent":"728.402038ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-2n27j\" "} {"level":"warn","ts":"2026-02-25T02:15:56.132824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.405668Z","time spent":"727.146975ms","remote":"127.0.0.1:42536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14331,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-25T02:15:56.132602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.074137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-vxgqt\" ","response":"range_response_count:1 size:3615"} {"level":"info","ts":"2026-02-25T02:15:56.132918Z","caller":"traceutil/trace.go:171","msg":"trace[957402373] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-vxgqt; range_end:; response_count:1; response_revision:483; }","duration":"729.437817ms","start":"2026-02-25T02:15:55.40347Z","end":"2026-02-25T02:15:56.132908Z","steps":["trace[957402373] 'agreement among raft nodes before linearized reading' (duration: 729.060517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:56.132948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.403456Z","time spent":"729.482428ms","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":3637,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-vxgqt\" "} {"level":"warn","ts":"2026-02-25T02:15:56.13299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.118483ms","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-25T02:15:56.133022Z","caller":"traceutil/trace.go:171","msg":"trace[128245706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:483; }","duration":"428.185885ms","start":"2026-02-25T02:15:55.704826Z","end":"2026-02-25T02:15:56.133012Z","steps":["trace[128245706] 'agreement among raft nodes before linearized reading' (duration: 428.061432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:56.133031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.380395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:15:56.133046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.704802Z","time spent":"428.238757ms","remote":"127.0.0.1:42374","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-25T02:15:56.133062Z","caller":"traceutil/trace.go:171","msg":"trace[1455195826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"729.421516ms","start":"2026-02-25T02:15:55.403631Z","end":"2026-02-25T02:15:56.133052Z","steps":["trace[1455195826] 'agreement among raft nodes before linearized reading' (duration: 729.374005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:15:56.133092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:15:55.403624Z","time spent":"729.462667ms","remote":"127.0.0.1:42040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:16:05.367378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.820159ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:05.367553Z","caller":"traceutil/trace.go:171","msg":"trace[584912825] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"209.952975ms","start":"2026-02-25T02:16:05.157584Z","end":"2026-02-25T02:16:05.367537Z","steps":["trace[584912825] 'process raft request' (duration: 209.893373ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:05.367659Z","caller":"traceutil/trace.go:171","msg":"trace[955388456] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"210.194861ms","start":"2026-02-25T02:16:05.157441Z","end":"2026-02-25T02:16:05.367636Z","steps":["trace[955388456] 'process raft request' (duration: 58.051184ms)","trace[955388456] 'compare' (duration: 151.526161ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:05.429988Z","caller":"traceutil/trace.go:171","msg":"trace[115816343] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"190.61211ms","start":"2026-02-25T02:16:05.239358Z","end":"2026-02-25T02:16:05.42997Z","steps":["trace[115816343] 'process raft request' (duration: 190.408035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:05.792172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.562865ms","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-25T02:16:05.792293Z","caller":"traceutil/trace.go:171","msg":"trace[232166479] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:535; }","duration":"129.698308ms","start":"2026-02-25T02:16:05.662575Z","end":"2026-02-25T02:16:05.792273Z","steps":["trace[232166479] 'range keys from in-memory index tree' (duration: 129.545854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:05.792374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.587086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:05.792558Z","caller":"traceutil/trace.go:171","msg":"trace[1778510263] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"357.928069ms","start":"2026-02-25T02:16:05.434603Z","end":"2026-02-25T02:16:05.792531Z","steps":["trace[1778510263] 'process raft request' (duration: 159.098986ms)","trace[1778510263] 'compare' (duration: 198.436373ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:05.792648Z","caller":"traceutil/trace.go:171","msg":"trace[770111638] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"291.427355ms","start":"2026-02-25T02:16:05.501203Z","end":"2026-02-25T02:16:05.79263Z","steps":["trace[770111638] 'process raft request' (duration: 291.387444ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:05.79265Z","caller":"traceutil/trace.go:171","msg":"trace[1250693535] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"347.150357ms","start":"2026-02-25T02:16:05.445477Z","end":"2026-02-25T02:16:05.792628Z","steps":["trace[1250693535] 'process raft request' (duration: 346.999323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:05.792693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:05.43458Z","time spent":"358.031131ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:05.792754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:05.445449Z","time spent":"347.2475ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":953,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:16:05.792723Z","caller":"traceutil/trace.go:171","msg":"trace[2034242728] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"347.026643ms","start":"2026-02-25T02:16:05.445642Z","end":"2026-02-25T02:16:05.792668Z","steps":["trace[2034242728] 'process raft request' (duration: 346.884809ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:05.792816Z","caller":"traceutil/trace.go:171","msg":"trace[980963060] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"347.105896ms","start":"2026-02-25T02:16:05.445696Z","end":"2026-02-25T02:16:05.792801Z","steps":["trace[980963060] 'process raft request' (duration: 346.862199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:05.792917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:05.445675Z","time spent":"347.191557ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:16:05.792924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:05.445632Z","time spent":"347.198438ms","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:16:06.01694Z","caller":"traceutil/trace.go:171","msg":"trace[247664205] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"197.829966ms","start":"2026-02-25T02:16:05.819067Z","end":"2026-02-25T02:16:06.016897Z","steps":["trace[247664205] 'process raft request' (duration: 195.02876ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:06.017042Z","caller":"traceutil/trace.go:171","msg":"trace[1590267378] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"197.061106ms","start":"2026-02-25T02:16:05.819955Z","end":"2026-02-25T02:16:06.017016Z","steps":["trace[1590267378] 'process raft request' (duration: 196.84871ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:06.017178Z","caller":"traceutil/trace.go:171","msg":"trace[1033919142] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"194.492515ms","start":"2026-02-25T02:16:05.822662Z","end":"2026-02-25T02:16:06.017154Z","steps":["trace[1033919142] 'process raft request' (duration: 194.207968ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:06.017315Z","caller":"traceutil/trace.go:171","msg":"trace[2095925333] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"191.137215ms","start":"2026-02-25T02:16:05.826144Z","end":"2026-02-25T02:16:06.017281Z","steps":["trace[2095925333] 'process raft request' (duration: 190.884488ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:06.017429Z","caller":"traceutil/trace.go:171","msg":"trace[1624487052] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"189.337446ms","start":"2026-02-25T02:16:05.828069Z","end":"2026-02-25T02:16:06.017406Z","steps":["trace[1624487052] 'process raft request' (duration: 189.044718ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:06.020597Z","caller":"traceutil/trace.go:171","msg":"trace[41854061] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"177.276289ms","start":"2026-02-25T02:16:05.843295Z","end":"2026-02-25T02:16:06.020571Z","steps":["trace[41854061] 'process raft request' (duration: 177.206967ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:06.020678Z","caller":"traceutil/trace.go:171","msg":"trace[1130463116] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"180.921897ms","start":"2026-02-25T02:16:05.839741Z","end":"2026-02-25T02:16:06.020663Z","steps":["trace[1130463116] 'process raft request' (duration: 180.62739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.631381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282699093s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:07.631697Z","caller":"traceutil/trace.go:171","msg":"trace[496448597] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.432013423s","start":"2026-02-25T02:16:06.199654Z","end":"2026-02-25T02:16:07.631668Z","steps":["trace[496448597] 'process raft request' (duration: 148.919859ms)","trace[496448597] 'compare' (duration: 1.282502448s)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:07.631843Z","caller":"traceutil/trace.go:171","msg":"trace[2130805246] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.430243485s","start":"2026-02-25T02:16:06.201574Z","end":"2026-02-25T02:16:07.631818Z","steps":["trace[2130805246] 'process raft request' (duration: 1.429951657s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.63185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.199641Z","time spent":"1.432129126s","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3574,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:07.631989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.201559Z","time spent":"1.4303808s","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:07.632118Z","caller":"traceutil/trace.go:171","msg":"trace[1269730392] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.428841987s","start":"2026-02-25T02:16:06.203238Z","end":"2026-02-25T02:16:07.63208Z","steps":["trace[1269730392] 'process raft request' (duration: 1.428414296s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.632204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.203228Z","time spent":"1.42893763s","remote":"127.0.0.1:42544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4411,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:07.632426Z","caller":"traceutil/trace.go:171","msg":"trace[88774744] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.374060351s","start":"2026-02-25T02:16:06.258351Z","end":"2026-02-25T02:16:07.632412Z","steps":["trace[88774744] 'process raft request' (duration: 1.373846056s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:07.63246Z","caller":"traceutil/trace.go:171","msg":"trace[715324691] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:656; }","duration":"1.378358417s","start":"2026-02-25T02:16:06.254095Z","end":"2026-02-25T02:16:07.632454Z","steps":["trace[715324691] 'read index received' (duration: 94.542914ms)","trace[715324691] 'applied index is now lower than readState.Index' (duration: 1.283814963s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:07.632501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.258336Z","time spent":"1.374130763s","remote":"127.0.0.1:42162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211522,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:07.632587Z","caller":"traceutil/trace.go:171","msg":"trace[1276691940] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.414586601s","start":"2026-02-25T02:16:06.217991Z","end":"2026-02-25T02:16:07.632577Z","steps":["trace[1276691940] 'process raft request' (duration: 1.414005485s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.632619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.378520173s","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-25T02:16:07.63264Z","caller":"traceutil/trace.go:171","msg":"trace[2000234929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.378576283s","start":"2026-02-25T02:16:06.254056Z","end":"2026-02-25T02:16:07.632633Z","steps":["trace[2000234929] 'agreement among raft nodes before linearized reading' (duration: 1.37845264s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.632651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.21797Z","time spent":"1.414647622s","remote":"127.0.0.1:42110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73420,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:07.632663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.254036Z","time spent":"1.378623195s","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:16:07.632662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342278389s","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-25T02:16:07.632419Z","caller":"traceutil/trace.go:171","msg":"trace[506956292] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"827.327841ms","start":"2026-02-25T02:16:06.805075Z","end":"2026-02-25T02:16:07.632403Z","steps":["trace[506956292] 'process raft request' (duration: 827.28947ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:07.632715Z","caller":"traceutil/trace.go:171","msg":"trace[665933446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.342384252s","start":"2026-02-25T02:16:06.290317Z","end":"2026-02-25T02:16:07.632701Z","steps":["trace[665933446] 'agreement among raft nodes before linearized reading' (duration: 1.342250169s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.632753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.290293Z","time spent":"1.342452223s","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:16:07.632754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.805048Z","time spent":"827.67841ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:07.632818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103401149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2n27j\" ","response":"range_response_count:1 size:16656"} {"level":"warn","ts":"2026-02-25T02:16:07.632865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.425237ms","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-25T02:16:07.632904Z","caller":"traceutil/trace.go:171","msg":"trace[2050709950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"172.500089ms","start":"2026-02-25T02:16:07.460393Z","end":"2026-02-25T02:16:07.632893Z","steps":["trace[2050709950] 'agreement among raft nodes before linearized reading' (duration: 172.369476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.632849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184463338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:07.632911Z","caller":"traceutil/trace.go:171","msg":"trace[1395871164] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2n27j; range_end:; response_count:1; response_revision:641; }","duration":"1.103546143s","start":"2026-02-25T02:16:06.529342Z","end":"2026-02-25T02:16:07.632888Z","steps":["trace[1395871164] 'agreement among raft nodes before linearized reading' (duration: 1.103388119s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.632957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.52932Z","time spent":"1.103628916s","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-2n27j\" "} {"level":"info","ts":"2026-02-25T02:16:07.632982Z","caller":"traceutil/trace.go:171","msg":"trace[2000414575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.184601912s","start":"2026-02-25T02:16:06.448344Z","end":"2026-02-25T02:16:07.632946Z","steps":["trace[2000414575] 'agreement among raft nodes before linearized reading' (duration: 1.184476428s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:07.633076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:06.448326Z","time spent":"1.184723496s","remote":"127.0.0.1:42040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:16:08.142705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100614431443519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:08.64387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100614431443519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:09.144943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100614431443519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:09.642554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000104565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:16:09.642667Z","caller":"traceutil/trace.go:171","msg":"trace[1528939109] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000258849s","start":"2026-02-25T02:16:07.642389Z","end":"2026-02-25T02:16:09.642648Z","steps":["trace[1528939109] 'agreement among raft nodes before linearized reading' (duration: 2.000128306s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:09.642716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.642379Z","time spent":"2.000327531s","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:16:09 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:16:09.645956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100614431443519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:10.147524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276100614431443519,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:16:10.580066Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.912673189s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T02:16:11.643006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000116583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:16:11.643082Z","caller":"traceutil/trace.go:171","msg":"trace[1622249919] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000242017s","start":"2026-02-25T02:16:09.642826Z","end":"2026-02-25T02:16:11.643068Z","steps":["trace[1622249919] 'agreement among raft nodes before linearized reading' (duration: 2.000147674s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:11.643118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:09.642809Z","time spent":"2.000302098s","remote":"127.0.0.1:42040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:16:11 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:16:11.889781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309291634s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:16:11.889894Z","caller":"traceutil/trace.go:171","msg":"trace[1287874785] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"4.251640618s","start":"2026-02-25T02:16:07.638233Z","end":"2026-02-25T02:16:11.889874Z","steps":["trace[1287874785] 'process raft request' (duration: 2.942018696s)","trace[1287874785] 'compare' (duration: 1.308248125s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:11.889974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.638213Z","time spent":"4.251713229s","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3828,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:11.890966Z","caller":"traceutil/trace.go:171","msg":"trace[601272847] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"4.25209454s","start":"2026-02-25T02:16:07.638819Z","end":"2026-02-25T02:16:11.890913Z","steps":["trace[601272847] 'process raft request' (duration: 4.251772272s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:11.891171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.638812Z","time spent":"4.252245694s","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:11.891361Z","caller":"traceutil/trace.go:171","msg":"trace[2146942118] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"4.252608084s","start":"2026-02-25T02:16:07.638733Z","end":"2026-02-25T02:16:11.891341Z","steps":["trace[2146942118] 'process raft request' (duration: 4.252127951s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.891401Z","caller":"traceutil/trace.go:171","msg":"trace[407575143] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"4.251961707s","start":"2026-02-25T02:16:07.639417Z","end":"2026-02-25T02:16:11.891379Z","steps":["trace[407575143] 'process raft request' (duration: 4.251778642s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:11.891439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.638716Z","time spent":"4.252690147s","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:11.891496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.639402Z","time spent":"4.252052459s","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:11.891562Z","caller":"traceutil/trace.go:171","msg":"trace[1631813968] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"4.24990089s","start":"2026-02-25T02:16:07.641635Z","end":"2026-02-25T02:16:11.891536Z","steps":["trace[1631813968] 'process raft request' (duration: 4.249733376s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.891547Z","caller":"traceutil/trace.go:171","msg":"trace[1801169860] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"4.250874988s","start":"2026-02-25T02:16:07.640636Z","end":"2026-02-25T02:16:11.891511Z","steps":["trace[1801169860] 'process raft request' (duration: 4.250668762s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:11.891684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.641624Z","time spent":"4.250000264s","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4665,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:11.891734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.640623Z","time spent":"4.251013941s","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4952,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:16:11.891685Z","caller":"traceutil/trace.go:171","msg":"trace[1862613529] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"4.247155537s","start":"2026-02-25T02:16:07.644507Z","end":"2026-02-25T02:16:11.891663Z","steps":["trace[1862613529] 'process raft request' (duration: 4.24692333s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:11.891906Z","caller":"traceutil/trace.go:171","msg":"trace[131791470] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"4.243569149s","start":"2026-02-25T02:16:07.64832Z","end":"2026-02-25T02:16:11.891889Z","steps":["trace[131791470] 'process raft request' (duration: 4.243298121s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:11.891948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.644477Z","time spent":"4.247359431s","remote":"127.0.0.1:42274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:11.892004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.648306Z","time spent":"4.243643201s","remote":"127.0.0.1:42304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:16:12.072344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.536597ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T02:16:12.072599Z","caller":"traceutil/trace.go:171","msg":"trace[891936153] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"425.431049ms","start":"2026-02-25T02:16:11.647149Z","end":"2026-02-25T02:16:12.07258Z","steps":["trace[891936153] 'process raft request' (duration: 425.309476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.072706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:11.64712Z","time spent":"425.525972ms","remote":"127.0.0.1:42132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:16:12.0727Z","caller":"traceutil/trace.go:171","msg":"trace[909926094] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:662; }","duration":"4.430216682s","start":"2026-02-25T02:16:07.642422Z","end":"2026-02-25T02:16:12.072639Z","steps":["trace[909926094] 'read index received' (duration: 2.937911164s)","trace[909926094] 'applied index is now lower than readState.Index' (duration: 1.492298638s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:12.07306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.274698944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T02:16:12.07306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.833321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:12.073151Z","caller":"traceutil/trace.go:171","msg":"trace[186519427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"4.274831977s","start":"2026-02-25T02:16:07.7983Z","end":"2026-02-25T02:16:12.073132Z","steps":["trace[186519427] 'agreement among raft nodes before linearized reading' (duration: 4.274631242s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.073166Z","caller":"traceutil/trace.go:171","msg":"trace[1378251545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"418.989715ms","start":"2026-02-25T02:16:11.654165Z","end":"2026-02-25T02:16:12.073155Z","steps":["trace[1378251545] 'agreement among raft nodes before linearized reading' (duration: 418.832541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.0732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:11.654153Z","time spent":"419.036456ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:16:12.073198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:07.79825Z","time spent":"4.27493767s","remote":"127.0.0.1:42374","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-25T02:16:12.073229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.455194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/cert-manager/cert-manager-startupapicheck:create-cert\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:12.073347Z","caller":"traceutil/trace.go:171","msg":"trace[194725202] range","detail":"{range_begin:/registry/roles/cert-manager/cert-manager-startupapicheck:create-cert; range_end:; response_count:0; response_revision:650; }","duration":"170.606058ms","start":"2026-02-25T02:16:11.902705Z","end":"2026-02-25T02:16:12.073312Z","steps":["trace[194725202] 'agreement among raft nodes before linearized reading' (duration: 170.243908ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.477674Z","caller":"traceutil/trace.go:171","msg":"trace[1851052866] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"290.787128ms","start":"2026-02-25T02:16:12.186863Z","end":"2026-02-25T02:16:12.47765Z","steps":["trace[1851052866] 'process raft request' (duration: 290.316515ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.68537Z","caller":"traceutil/trace.go:171","msg":"trace[1621121131] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"117.125917ms","start":"2026-02-25T02:16:12.568208Z","end":"2026-02-25T02:16:12.685334Z","steps":["trace[1621121131] 'read index received' (duration: 116.950362ms)","trace[1621121131] 'applied index is now lower than readState.Index' (duration: 173.765µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:12.685436Z","caller":"traceutil/trace.go:171","msg":"trace[1933363836] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"137.993233ms","start":"2026-02-25T02:16:12.547383Z","end":"2026-02-25T02:16:12.685377Z","steps":["trace[1933363836] 'process raft request' (duration: 137.713205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:12.685596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.353003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/cert-manager/cert-manager-startupapicheck:create-cert\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:16:12.685697Z","caller":"traceutil/trace.go:171","msg":"trace[103753410] range","detail":"{range_begin:/registry/rolebindings/cert-manager/cert-manager-startupapicheck:create-cert; range_end:; response_count:0; response_revision:654; }","duration":"117.497147ms","start":"2026-02-25T02:16:12.568178Z","end":"2026-02-25T02:16:12.685675Z","steps":["trace[103753410] 'agreement among raft nodes before linearized reading' (duration: 117.285081ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.713552Z","caller":"traceutil/trace.go:171","msg":"trace[872785451] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"142.409753ms","start":"2026-02-25T02:16:12.571102Z","end":"2026-02-25T02:16:12.713512Z","steps":["trace[872785451] 'process raft request' (duration: 142.097805ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:12.966822Z","caller":"traceutil/trace.go:171","msg":"trace[1450321187] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"184.087533ms","start":"2026-02-25T02:16:12.782695Z","end":"2026-02-25T02:16:12.966783Z","steps":["trace[1450321187] 'process raft request' (duration: 177.584517ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:13.352339Z","caller":"traceutil/trace.go:171","msg":"trace[1450398963] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"184.531716ms","start":"2026-02-25T02:16:13.167732Z","end":"2026-02-25T02:16:13.352263Z","steps":["trace[1450398963] 'process raft request' (duration: 178.932394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:14.187298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.367017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T02:16:14.187346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.904818ms","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-25T02:16:14.187426Z","caller":"traceutil/trace.go:171","msg":"trace[909188633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:672; }","duration":"290.571262ms","start":"2026-02-25T02:16:13.896827Z","end":"2026-02-25T02:16:14.187398Z","steps":["trace[909188633] 'range keys from in-memory index tree' (duration: 290.14585ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:14.187433Z","caller":"traceutil/trace.go:171","msg":"trace[1463268318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"292.050812ms","start":"2026-02-25T02:16:13.895357Z","end":"2026-02-25T02:16:14.187408Z","steps":["trace[1463268318] 'range keys from in-memory index tree' (duration: 291.639921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:14.187558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.698515ms","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-25T02:16:14.187666Z","caller":"traceutil/trace.go:171","msg":"trace[1153191691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:672; }","duration":"290.86334ms","start":"2026-02-25T02:16:13.896778Z","end":"2026-02-25T02:16:14.187641Z","steps":["trace[1153191691] 'range keys from in-memory index tree' (duration: 290.50348ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:28.823227Z","caller":"traceutil/trace.go:171","msg":"trace[1672444732] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"154.249074ms","start":"2026-02-25T02:16:28.668953Z","end":"2026-02-25T02:16:28.823202Z","steps":["trace[1672444732] 'process raft request' (duration: 145.466085ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:28.867267Z","caller":"traceutil/trace.go:171","msg":"trace[949145747] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"188.515443ms","start":"2026-02-25T02:16:28.678726Z","end":"2026-02-25T02:16:28.867241Z","steps":["trace[949145747] 'process raft request' (duration: 188.363199ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:29.052082Z","caller":"traceutil/trace.go:171","msg":"trace[513516215] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"220.968114ms","start":"2026-02-25T02:16:28.831095Z","end":"2026-02-25T02:16:29.052063Z","steps":["trace[513516215] 'process raft request' (duration: 220.925013ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:29.052374Z","caller":"traceutil/trace.go:171","msg":"trace[2027011519] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"223.41334ms","start":"2026-02-25T02:16:28.828951Z","end":"2026-02-25T02:16:29.052364Z","steps":["trace[2027011519] 'process raft request' (duration: 222.992299ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:29.052563Z","caller":"traceutil/trace.go:171","msg":"trace[115529407] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"225.006114ms","start":"2026-02-25T02:16:28.827501Z","end":"2026-02-25T02:16:29.052507Z","steps":["trace[115529407] 'process raft request' (duration: 224.248283ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:30.436009Z","caller":"traceutil/trace.go:171","msg":"trace[44544723] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"112.815891ms","start":"2026-02-25T02:16:30.323166Z","end":"2026-02-25T02:16:30.435982Z","steps":["trace[44544723] 'process raft request' (duration: 96.671703ms)","trace[44544723] 'compare' (duration: 15.969673ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:30.436293Z","caller":"traceutil/trace.go:171","msg":"trace[839258210] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"112.982075ms","start":"2026-02-25T02:16:30.323267Z","end":"2026-02-25T02:16:30.436249Z","steps":["trace[839258210] 'process raft request' (duration: 112.681697ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:30.436294Z","caller":"traceutil/trace.go:171","msg":"trace[1892333273] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"107.421504ms","start":"2026-02-25T02:16:30.328849Z","end":"2026-02-25T02:16:30.43627Z","steps":["trace[1892333273] 'process raft request' (duration: 107.297701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:38.585707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.646225ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T02:16:38.585926Z","caller":"traceutil/trace.go:171","msg":"trace[2094359539] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:975; }","duration":"112.752001ms","start":"2026-02-25T02:16:38.473159Z","end":"2026-02-25T02:16:38.585911Z","steps":["trace[2094359539] 'read index received' (duration: 96.662035ms)","trace[2094359539] 'applied index is now lower than readState.Index' (duration: 16.088766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:16:38.586077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.908935ms","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-25T02:16:38.586026Z","caller":"traceutil/trace.go:171","msg":"trace[489281305] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"122.438022ms","start":"2026-02-25T02:16:38.463528Z","end":"2026-02-25T02:16:38.585966Z","steps":["trace[489281305] 'process raft request' (duration: 122.268087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:38.58614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.35545ms","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-25T02:16:38.586161Z","caller":"traceutil/trace.go:171","msg":"trace[28945179] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:947; }","duration":"105.399941ms","start":"2026-02-25T02:16:38.480758Z","end":"2026-02-25T02:16:38.586158Z","steps":["trace[28945179] 'agreement among raft nodes before linearized reading' (duration: 105.36453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:38.586237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.558207ms","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-25T02:16:38.586343Z","caller":"traceutil/trace.go:171","msg":"trace[139519230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"101.700391ms","start":"2026-02-25T02:16:38.484623Z","end":"2026-02-25T02:16:38.586323Z","steps":["trace[139519230] 'agreement among raft nodes before linearized reading' (duration: 101.506815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:38.586405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.111092ms","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-25T02:16:38.586426Z","caller":"traceutil/trace.go:171","msg":"trace[1594775663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"102.142573ms","start":"2026-02-25T02:16:38.484278Z","end":"2026-02-25T02:16:38.586421Z","steps":["trace[1594775663] 'agreement among raft nodes before linearized reading' (duration: 102.093682ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:38.586104Z","caller":"traceutil/trace.go:171","msg":"trace[48863696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:947; }","duration":"112.978227ms","start":"2026-02-25T02:16:38.473118Z","end":"2026-02-25T02:16:38.586096Z","steps":["trace[48863696] 'agreement among raft nodes before linearized reading' (duration: 112.865894ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:38.756992Z","caller":"traceutil/trace.go:171","msg":"trace[129339127] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"117.068757ms","start":"2026-02-25T02:16:38.639892Z","end":"2026-02-25T02:16:38.756961Z","steps":["trace[129339127] 'process raft request' (duration: 47.572667ms)","trace[129339127] 'compare' (duration: 69.349316ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:38.787018Z","caller":"traceutil/trace.go:171","msg":"trace[1566277933] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"143.764379ms","start":"2026-02-25T02:16:38.643234Z","end":"2026-02-25T02:16:38.786999Z","steps":["trace[1566277933] 'process raft request' (duration: 143.661476ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:54.996063Z","caller":"traceutil/trace.go:171","msg":"trace[1881216390] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"269.530141ms","start":"2026-02-25T02:16:54.726503Z","end":"2026-02-25T02:16:54.996033Z","steps":["trace[1881216390] 'read index received' (duration: 269.267704ms)","trace[1881216390] 'applied index is now lower than readState.Index' (duration: 261.167µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:16:54.996173Z","caller":"traceutil/trace.go:171","msg":"trace[295564128] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"305.824423ms","start":"2026-02-25T02:16:54.690315Z","end":"2026-02-25T02:16:54.996139Z","steps":["trace[295564128] 'process raft request' (duration: 305.548756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:54.996353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:16:54.69029Z","time spent":"305.970547ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:16:54.996367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.851059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T02:16:54.996366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.882447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:16:54.996425Z","caller":"traceutil/trace.go:171","msg":"trace[1914810278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1079; }","duration":"269.950462ms","start":"2026-02-25T02:16:54.726454Z","end":"2026-02-25T02:16:54.996404Z","steps":["trace[1914810278] 'agreement among raft nodes before linearized reading' (duration: 269.750457ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:54.996453Z","caller":"traceutil/trace.go:171","msg":"trace[1111460315] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1079; }","duration":"114.999831ms","start":"2026-02-25T02:16:54.881434Z","end":"2026-02-25T02:16:54.996434Z","steps":["trace[1111460315] 'agreement among raft nodes before linearized reading' (duration: 114.866137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:54.996468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.088496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:16:54.996536Z","caller":"traceutil/trace.go:171","msg":"trace[201211967] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1079; }","duration":"148.203339ms","start":"2026-02-25T02:16:54.848319Z","end":"2026-02-25T02:16:54.996522Z","steps":["trace[201211967] 'agreement among raft nodes before linearized reading' (duration: 148.095587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:54.996692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.804458ms","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-25T02:16:54.996785Z","caller":"traceutil/trace.go:171","msg":"trace[539766065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1079; }","duration":"103.928162ms","start":"2026-02-25T02:16:54.892835Z","end":"2026-02-25T02:16:54.996763Z","steps":["trace[539766065] 'agreement among raft nodes before linearized reading' (duration: 103.682835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:54.996691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.34017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-929hn\" ","response":"range_response_count:1 size:4153"} {"level":"info","ts":"2026-02-25T02:16:54.996886Z","caller":"traceutil/trace.go:171","msg":"trace[1727282638] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-929hn; range_end:; response_count:1; response_revision:1079; }","duration":"210.630648ms","start":"2026-02-25T02:16:54.786238Z","end":"2026-02-25T02:16:54.996869Z","steps":["trace[1727282638] 'agreement among raft nodes before linearized reading' (duration: 210.215487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:16:54.996844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.877476ms","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-25T02:16:54.997111Z","caller":"traceutil/trace.go:171","msg":"trace[1633917856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"224.614726ms","start":"2026-02-25T02:16:54.772476Z","end":"2026-02-25T02:16:54.997091Z","steps":["trace[1633917856] 'agreement among raft nodes before linearized reading' (duration: 223.817784ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:16:58.973285Z","caller":"traceutil/trace.go:171","msg":"trace[1665116265] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"151.102077ms","start":"2026-02-25T02:16:58.822159Z","end":"2026-02-25T02:16:58.973261Z","steps":["trace[1665116265] 'process raft request' (duration: 150.956463ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:01.000919Z","caller":"traceutil/trace.go:171","msg":"trace[1152739885] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"169.793873ms","start":"2026-02-25T02:17:00.831093Z","end":"2026-02-25T02:17:01.000887Z","steps":["trace[1152739885] 'process raft request' (duration: 169.587537ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:03.494383Z","caller":"traceutil/trace.go:171","msg":"trace[529850047] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"454.871925ms","start":"2026-02-25T02:17:03.039485Z","end":"2026-02-25T02:17:03.494357Z","steps":["trace[529850047] 'read index received' (duration: 454.640699ms)","trace[529850047] 'applied index is now lower than readState.Index' (duration: 229.746µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:17:03.494444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:02.962983Z","time spent":"531.451936ms","remote":"127.0.0.1:42088","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T02:17:03.494541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.029329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:17:03.494751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.200532ms","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-25T02:17:03.494784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.790386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T02:17:03.494825Z","caller":"traceutil/trace.go:171","msg":"trace[1021448791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1201; }","duration":"442.312095ms","start":"2026-02-25T02:17:03.052475Z","end":"2026-02-25T02:17:03.494787Z","steps":["trace[1021448791] 'agreement among raft nodes before linearized reading' (duration: 442.044348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.494861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.263114ms","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-25T02:17:03.494849Z","caller":"traceutil/trace.go:171","msg":"trace[243063592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1201; }","duration":"436.874908ms","start":"2026-02-25T02:17:03.057956Z","end":"2026-02-25T02:17:03.494831Z","steps":["trace[243063592] 'agreement among raft nodes before linearized reading' (duration: 436.669552ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:03.494914Z","caller":"traceutil/trace.go:171","msg":"trace[844712677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1201; }","duration":"442.311355ms","start":"2026-02-25T02:17:03.052583Z","end":"2026-02-25T02:17:03.494895Z","steps":["trace[844712677] 'agreement among raft nodes before linearized reading' (duration: 442.173212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.494923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.052463Z","time spent":"442.398437ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:17:03.494953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.052521Z","time spent":"442.421188ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:17:03.494953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.643727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T02:17:03.494988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.057946Z","time spent":"436.987611ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T02:17:03.495056Z","caller":"traceutil/trace.go:171","msg":"trace[2035095234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1201; }","duration":"441.788011ms","start":"2026-02-25T02:17:03.053244Z","end":"2026-02-25T02:17:03.495032Z","steps":["trace[2035095234] 'agreement among raft nodes before linearized reading' (duration: 441.495123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.495113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.053233Z","time spent":"441.870723ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:17:03.494645Z","caller":"traceutil/trace.go:171","msg":"trace[1847825170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"455.176143ms","start":"2026-02-25T02:17:03.039445Z","end":"2026-02-25T02:17:03.494621Z","steps":["trace[1847825170] 'agreement among raft nodes before linearized reading' (duration: 455.012159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.495403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.039425Z","time spent":"455.900163ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:17:03.604648Z","caller":"traceutil/trace.go:171","msg":"trace[1336702207] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"108.402742ms","start":"2026-02-25T02:17:03.49621Z","end":"2026-02-25T02:17:03.604613Z","steps":["trace[1336702207] 'process raft request' (duration: 70.98709ms)","trace[1336702207] 'compare' (duration: 37.271468ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:17:03.891533Z","caller":"traceutil/trace.go:171","msg":"trace[495927087] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"391.506261ms","start":"2026-02-25T02:17:03.499991Z","end":"2026-02-25T02:17:03.891497Z","steps":["trace[495927087] 'process raft request' (duration: 389.915818ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:03.891584Z","caller":"traceutil/trace.go:171","msg":"trace[1736813959] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"391.075459ms","start":"2026-02-25T02:17:03.500485Z","end":"2026-02-25T02:17:03.891561Z","steps":["trace[1736813959] 'process raft request' (duration: 390.995867ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:03.891596Z","caller":"traceutil/trace.go:171","msg":"trace[513761207] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"391.414499ms","start":"2026-02-25T02:17:03.500142Z","end":"2026-02-25T02:17:03.891556Z","steps":["trace[513761207] 'process raft request' (duration: 391.304346ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:03.891623Z","caller":"traceutil/trace.go:171","msg":"trace[628428702] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"391.565853ms","start":"2026-02-25T02:17:03.500027Z","end":"2026-02-25T02:17:03.891593Z","steps":["trace[628428702] 'process raft request' (duration: 391.318776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.891718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.98946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-02-25T02:17:03.891707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.500477Z","time spent":"391.159142ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:17:03.891741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.500009Z","time spent":"391.660935ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:17:03.891741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.500118Z","time spent":"391.548642ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:17:03.891707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.499969Z","time spent":"391.651154ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:17:03.891771Z","caller":"traceutil/trace.go:171","msg":"trace[630145076] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1206; }","duration":"390.090292ms","start":"2026-02-25T02:17:03.501666Z","end":"2026-02-25T02:17:03.891756Z","steps":["trace[630145076] 'agreement among raft nodes before linearized reading' (duration: 389.929488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:03.891815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:03.501648Z","time spent":"390.158094ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-02-25T02:17:03.891544Z","caller":"traceutil/trace.go:171","msg":"trace[1109442879] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1241; }","duration":"389.802465ms","start":"2026-02-25T02:17:03.501714Z","end":"2026-02-25T02:17:03.891517Z","steps":["trace[1109442879] 'read index received' (duration: 65.551483ms)","trace[1109442879] 'applied index is now lower than readState.Index' (duration: 324.250091ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:17:03.891983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.669001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-25T02:17:03.892048Z","caller":"traceutil/trace.go:171","msg":"trace[1501901348] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1206; }","duration":"284.771573ms","start":"2026-02-25T02:17:03.607261Z","end":"2026-02-25T02:17:03.892032Z","steps":["trace[1501901348] 'agreement among raft nodes before linearized reading' (duration: 284.65976ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:17:34.802328Z","caller":"traceutil/trace.go:171","msg":"trace[333619125] linearizableReadLoop","detail":"{readStateIndex:1619; appliedIndex:1618; }","duration":"257.695801ms","start":"2026-02-25T02:17:34.544602Z","end":"2026-02-25T02:17:34.802298Z","steps":["trace[333619125] 'read index received' (duration: 257.491735ms)","trace[333619125] 'applied index is now lower than readState.Index' (duration: 202.846µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:17:34.802387Z","caller":"traceutil/trace.go:171","msg":"trace[1618192519] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"305.680639ms","start":"2026-02-25T02:17:34.496676Z","end":"2026-02-25T02:17:34.802356Z","steps":["trace[1618192519] 'process raft request' (duration: 305.439703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:17:34.80253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:17:34.496637Z","time spent":"305.810463ms","remote":"127.0.0.1:42374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:17:34.802973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.327698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-02-25T02:17:34.803066Z","caller":"traceutil/trace.go:171","msg":"trace[729975338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1550; }","duration":"258.490172ms","start":"2026-02-25T02:17:34.544557Z","end":"2026-02-25T02:17:34.803047Z","steps":["trace[729975338] 'agreement among raft nodes before linearized reading' (duration: 257.853145ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:25:20.038475Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2122} {"level":"info","ts":"2026-02-25T02:25:20.091115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2122,"took":"51.820225ms","hash":4242407843,"current-db-size-bytes":14135296,"current-db-size":"14 MB","current-db-size-in-use-bytes":4280320,"current-db-size-in-use":"4.3 MB"} {"level":"info","ts":"2026-02-25T02:25:20.091185Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4242407843,"revision":2122,"compact-revision":-1} {"level":"info","ts":"2026-02-25T02:30:20.044218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3053} {"level":"info","ts":"2026-02-25T02:30:20.062574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3053,"took":"17.671547ms","hash":2371817885,"current-db-size-bytes":14135296,"current-db-size":"14 MB","current-db-size-in-use-bytes":4661248,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T02:30:20.062625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2371817885,"revision":3053,"compact-revision":2122}