{"level":"warn","ts":"2026-02-18T14:43:20.75902Z","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-18T14:43:20.759105Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-18T14:43:20.759175Z","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-18T14:43:20.759194Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-18T14:43:20.759212Z","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-18T14:43:20.763129Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-18T14:43:20.763256Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-18T14:43:20.772317Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.845277ms"} {"level":"info","ts":"2026-02-18T14:43:20.799983Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-18T14:43:20.800046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T14:43:20.800065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-18T14:43:20.800075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T14:43:20.800085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-18T14:43:20.800118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-18T14:43:20.81109Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T14:43:20.819788Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T14:43:20.82271Z","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-18T14:43:20.83368Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T14:43:20.833863Z","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-18T14:43:20.833907Z","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-18T14:43:20.833944Z","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-18T14:43:20.837753Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T14:43:20.838388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-18T14:43:20.838529Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-18T14:43:20.850034Z","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-18T14:43:20.850327Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-18T14:43:20.850342Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-18T14:43:20.850533Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T14:43:20.850554Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T14:43:21.500437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T14:43:21.50065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T14:43:21.50068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-18T14:43:21.500731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-18T14:43:21.500767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-18T14:43:21.500798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-18T14:43:21.500841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-18T14:43:21.503755Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T14:43:21.508066Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:43:21.508125Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:43:21.508257Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T14:43:21.508302Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T14:43:21.508382Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:43:21.509885Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:43:21.510001Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:43:21.510055Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:43:21.511702Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T14:43:21.516458Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"warn","ts":"2026-02-18T14:43:30.418122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.902736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T14:43:30.418214Z","caller":"traceutil/trace.go:171","msg":"trace[1758195556] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:340; }","duration":"132.061179ms","start":"2026-02-18T14:43:30.286137Z","end":"2026-02-18T14:43:30.418198Z","steps":["trace[1758195556] 'range keys from in-memory index tree' (duration: 131.833764ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:30.598666Z","caller":"traceutil/trace.go:171","msg":"trace[415680159] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"113.189126ms","start":"2026-02-18T14:43:30.485455Z","end":"2026-02-18T14:43:30.598644Z","steps":["trace[415680159] 'read index received' (duration: 112.989011ms)","trace[415680159] 'applied index is now lower than readState.Index' (duration: 199.215µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:43:30.598938Z","caller":"traceutil/trace.go:171","msg":"trace[723050592] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"116.715113ms","start":"2026-02-18T14:43:30.482212Z","end":"2026-02-18T14:43:30.598927Z","steps":["trace[723050592] 'process raft request' (duration: 116.323754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:30.59913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.660527ms","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-18T14:43:30.599166Z","caller":"traceutil/trace.go:171","msg":"trace[1815697956] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:341; }","duration":"113.73634ms","start":"2026-02-18T14:43:30.485417Z","end":"2026-02-18T14:43:30.599153Z","steps":["trace[1815697956] 'agreement among raft nodes before linearized reading' (duration: 113.665078ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:30.892851Z","caller":"traceutil/trace.go:171","msg":"trace[893367988] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"142.380442ms","start":"2026-02-18T14:43:30.750441Z","end":"2026-02-18T14:43:30.892822Z","steps":["trace[893367988] 'process raft request' (duration: 142.046324ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:30.894526Z","caller":"traceutil/trace.go:171","msg":"trace[1181637517] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"147.858907ms","start":"2026-02-18T14:43:30.746649Z","end":"2026-02-18T14:43:30.894507Z","steps":["trace[1181637517] 'process raft request' (duration: 68.659104ms)","trace[1181637517] 'compare' (duration: 76.680811ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:43:30.894843Z","caller":"traceutil/trace.go:171","msg":"trace[2112153978] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"144.667199ms","start":"2026-02-18T14:43:30.750149Z","end":"2026-02-18T14:43:30.894817Z","steps":["trace[2112153978] 'read index received' (duration: 65.236109ms)","trace[2112153978] 'applied index is now lower than readState.Index' (duration: 79.299677ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:43:30.897298Z","caller":"traceutil/trace.go:171","msg":"trace[46649743] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"147.058478ms","start":"2026-02-18T14:43:30.750213Z","end":"2026-02-18T14:43:30.897271Z","steps":["trace[46649743] 'process raft request' (duration: 142.215389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:30.897683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.511358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-02-18T14:43:30.897762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.183063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T14:43:30.905283Z","caller":"traceutil/trace.go:171","msg":"trace[768905684] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:347; }","duration":"117.734888ms","start":"2026-02-18T14:43:30.787535Z","end":"2026-02-18T14:43:30.90527Z","steps":["trace[768905684] 'agreement among raft nodes before linearized reading' (duration: 110.178803ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:30.900977Z","caller":"traceutil/trace.go:171","msg":"trace[1115154648] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:347; }","duration":"150.814369ms","start":"2026-02-18T14:43:30.750145Z","end":"2026-02-18T14:43:30.900959Z","steps":["trace[1115154648] 'agreement among raft nodes before linearized reading' (duration: 147.453447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:31.556465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.570288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:43:31.556814Z","caller":"traceutil/trace.go:171","msg":"trace[724820545] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"281.423523ms","start":"2026-02-18T14:43:31.275364Z","end":"2026-02-18T14:43:31.556788Z","steps":["trace[724820545] 'process raft request' (duration: 173.328332ms)","trace[724820545] 'compare' (duration: 105.446226ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:43:31.557144Z","caller":"traceutil/trace.go:171","msg":"trace[281083245] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"278.530102ms","start":"2026-02-18T14:43:31.278608Z","end":"2026-02-18T14:43:31.557138Z","steps":["trace[281083245] 'process raft request' (duration: 278.087731ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:31.557343Z","caller":"traceutil/trace.go:171","msg":"trace[2133463474] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"275.922748ms","start":"2026-02-18T14:43:31.2814Z","end":"2026-02-18T14:43:31.557323Z","steps":["trace[2133463474] 'process raft request' (duration: 275.386935ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:34.975941Z","caller":"traceutil/trace.go:171","msg":"trace[344298164] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"179.147977ms","start":"2026-02-18T14:43:34.796775Z","end":"2026-02-18T14:43:34.975923Z","steps":["trace[344298164] 'process raft request' (duration: 142.515761ms)","trace[344298164] 'compare' (duration: 36.528144ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:43:35.03051Z","caller":"traceutil/trace.go:171","msg":"trace[1804078264] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"227.915328ms","start":"2026-02-18T14:43:34.802581Z","end":"2026-02-18T14:43:35.030497Z","steps":["trace[1804078264] 'process raft request' (duration: 227.644122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:35.474396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.605191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:43:35.474636Z","caller":"traceutil/trace.go:171","msg":"trace[1869006329] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"433.805983ms","start":"2026-02-18T14:43:35.040798Z","end":"2026-02-18T14:43:35.474604Z","steps":["trace[1869006329] 'process raft request' (duration: 212.784792ms)","trace[1869006329] 'compare' (duration: 220.523268ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:43:35.474731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:43:35.04079Z","time spent":"433.917656ms","remote":"127.0.0.1:41134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:43:35.823778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.539618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:43:35.82386Z","caller":"traceutil/trace.go:171","msg":"trace[831708549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"146.751862ms","start":"2026-02-18T14:43:35.677089Z","end":"2026-02-18T14:43:35.823841Z","steps":["trace[831708549] 'range keys from in-memory index tree' (duration: 146.423825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:35.824184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.753791ms","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-18T14:43:35.824221Z","caller":"traceutil/trace.go:171","msg":"trace[103108552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"174.822022ms","start":"2026-02-18T14:43:35.649389Z","end":"2026-02-18T14:43:35.824211Z","steps":["trace[103108552] 'range keys from in-memory index tree' (duration: 174.667739ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:42.253763Z","caller":"traceutil/trace.go:171","msg":"trace[1874263786] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"349.660436ms","start":"2026-02-18T14:43:41.904085Z","end":"2026-02-18T14:43:42.253746Z","steps":["trace[1874263786] 'read index received' (duration: 349.481732ms)","trace[1874263786] 'applied index is now lower than readState.Index' (duration: 178.084µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:43:42.253985Z","caller":"traceutil/trace.go:171","msg":"trace[1133505519] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"356.986273ms","start":"2026-02-18T14:43:41.896992Z","end":"2026-02-18T14:43:42.253978Z","steps":["trace[1133505519] 'process raft request' (duration: 356.622445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:42.254062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:43:41.896974Z","time spent":"357.037285ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:43:42.254208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.118217ms","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-18T14:43:42.254235Z","caller":"traceutil/trace.go:171","msg":"trace[1481586080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:454; }","duration":"350.165517ms","start":"2026-02-18T14:43:41.904062Z","end":"2026-02-18T14:43:42.254228Z","steps":["trace[1481586080] 'agreement among raft nodes before linearized reading' (duration: 350.120666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:42.25426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:43:41.904053Z","time spent":"350.202509ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:43:42.254353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.60134ms","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-18T14:43:42.254377Z","caller":"traceutil/trace.go:171","msg":"trace[507058940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:454; }","duration":"317.651012ms","start":"2026-02-18T14:43:41.936719Z","end":"2026-02-18T14:43:42.254371Z","steps":["trace[507058940] 'agreement among raft nodes before linearized reading' (duration: 317.615141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:42.254412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:43:41.936704Z","time spent":"317.701883ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:43:42.254559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.175503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:43:42.254584Z","caller":"traceutil/trace.go:171","msg":"trace[250455157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:454; }","duration":"121.233745ms","start":"2026-02-18T14:43:42.133345Z","end":"2026-02-18T14:43:42.254578Z","steps":["trace[250455157] 'agreement among raft nodes before linearized reading' (duration: 121.197414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:42.254686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.981746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T14:43:42.25471Z","caller":"traceutil/trace.go:171","msg":"trace[984618422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"315.025198ms","start":"2026-02-18T14:43:41.939679Z","end":"2026-02-18T14:43:42.254704Z","steps":["trace[984618422] 'agreement among raft nodes before linearized reading' (duration: 314.989527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:42.254727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:43:41.939669Z","time spent":"315.054318ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T14:43:42.522624Z","caller":"traceutil/trace.go:171","msg":"trace[1737117565] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"260.547878ms","start":"2026-02-18T14:43:42.262059Z","end":"2026-02-18T14:43:42.522607Z","steps":["trace[1737117565] 'process raft request' (duration: 220.340334ms)","trace[1737117565] 'compare' (duration: 40.008428ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:43:42.524262Z","caller":"traceutil/trace.go:171","msg":"trace[112323825] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"261.145502ms","start":"2026-02-18T14:43:42.263105Z","end":"2026-02-18T14:43:42.524251Z","steps":["trace[112323825] 'process raft request' (duration: 261.107031ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:42.524375Z","caller":"traceutil/trace.go:171","msg":"trace[1022135865] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"262.189008ms","start":"2026-02-18T14:43:42.262181Z","end":"2026-02-18T14:43:42.52437Z","steps":["trace[1022135865] 'process raft request' (duration: 261.995153ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:52.685595Z","caller":"traceutil/trace.go:171","msg":"trace[1362208773] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"113.432096ms","start":"2026-02-18T14:43:52.572149Z","end":"2026-02-18T14:43:52.685581Z","steps":["trace[1362208773] 'read index received' (duration: 113.318893ms)","trace[1362208773] 'applied index is now lower than readState.Index' (duration: 112.453µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:43:52.685732Z","caller":"traceutil/trace.go:171","msg":"trace[506874136] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"117.622977ms","start":"2026-02-18T14:43:52.568103Z","end":"2026-02-18T14:43:52.685726Z","steps":["trace[506874136] 'process raft request' (duration: 117.400992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:52.685879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.716144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T14:43:52.685898Z","caller":"traceutil/trace.go:171","msg":"trace[507662708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"113.744804ms","start":"2026-02-18T14:43:52.572146Z","end":"2026-02-18T14:43:52.685891Z","steps":["trace[507662708] 'agreement among raft nodes before linearized reading' (duration: 113.701753ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:53.278301Z","caller":"traceutil/trace.go:171","msg":"trace[1753990544] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"589.418821ms","start":"2026-02-18T14:43:52.688855Z","end":"2026-02-18T14:43:53.278274Z","steps":["trace[1753990544] 'process raft request' (duration: 589.227106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:53.278508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:43:52.688844Z","time spent":"589.565804ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:43:53.280787Z","caller":"traceutil/trace.go:171","msg":"trace[195109306] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"175.265254ms","start":"2026-02-18T14:43:53.105504Z","end":"2026-02-18T14:43:53.280769Z","steps":["trace[195109306] 'read index received' (duration: 173.493441ms)","trace[195109306] 'applied index is now lower than readState.Index' (duration: 1.770903ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:43:53.281043Z","caller":"traceutil/trace.go:171","msg":"trace[1087345087] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"591.004569ms","start":"2026-02-18T14:43:52.690028Z","end":"2026-02-18T14:43:53.281032Z","steps":["trace[1087345087] 'process raft request' (duration: 590.566659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:53.281141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:43:52.690021Z","time spent":"591.04604ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:43:53.281426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.92597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-18T14:43:53.281462Z","caller":"traceutil/trace.go:171","msg":"trace[1174324528] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:515; }","duration":"176.006081ms","start":"2026-02-18T14:43:53.105448Z","end":"2026-02-18T14:43:53.281454Z","steps":["trace[1174324528] 'agreement among raft nodes before linearized reading' (duration: 175.826467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:53.281612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.650933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:43:53.281632Z","caller":"traceutil/trace.go:171","msg":"trace[124141706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"149.687374ms","start":"2026-02-18T14:43:53.131939Z","end":"2026-02-18T14:43:53.281626Z","steps":["trace[124141706] 'agreement among raft nodes before linearized reading' (duration: 149.653973ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:54.285967Z","caller":"traceutil/trace.go:171","msg":"trace[1814298305] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"164.417891ms","start":"2026-02-18T14:43:54.121533Z","end":"2026-02-18T14:43:54.285951Z","steps":["trace[1814298305] 'process raft request' (duration: 164.329779ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:54.950234Z","caller":"traceutil/trace.go:171","msg":"trace[590771349] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"219.263998ms","start":"2026-02-18T14:43:54.730957Z","end":"2026-02-18T14:43:54.950221Z","steps":["trace[590771349] 'read index received' (duration: 219.142226ms)","trace[590771349] 'applied index is now lower than readState.Index' (duration: 121.262µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:43:54.95032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.34902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:43:54.950367Z","caller":"traceutil/trace.go:171","msg":"trace[278834563] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:519; }","duration":"219.401322ms","start":"2026-02-18T14:43:54.73093Z","end":"2026-02-18T14:43:54.950331Z","steps":["trace[278834563] 'agreement among raft nodes before linearized reading' (duration: 219.343351ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:54.950547Z","caller":"traceutil/trace.go:171","msg":"trace[1865153837] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"259.563105ms","start":"2026-02-18T14:43:54.690977Z","end":"2026-02-18T14:43:54.95054Z","steps":["trace[1865153837] 'process raft request' (duration: 259.159155ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:56.513963Z","caller":"traceutil/trace.go:171","msg":"trace[1471842986] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"205.504575ms","start":"2026-02-18T14:43:56.308427Z","end":"2026-02-18T14:43:56.513932Z","steps":["trace[1471842986] 'process raft request' (duration: 205.363592ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:59.202771Z","caller":"traceutil/trace.go:171","msg":"trace[413924902] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"193.30914ms","start":"2026-02-18T14:43:59.00944Z","end":"2026-02-18T14:43:59.20275Z","steps":["trace[413924902] 'read index received' (duration: 193.134716ms)","trace[413924902] 'applied index is now lower than readState.Index' (duration: 173.544µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:43:59.203262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.809852ms","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-18T14:43:59.203297Z","caller":"traceutil/trace.go:171","msg":"trace[2024436005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"193.898514ms","start":"2026-02-18T14:43:59.009386Z","end":"2026-02-18T14:43:59.203284Z","steps":["trace[2024436005] 'agreement among raft nodes before linearized reading' (duration: 193.73752ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:59.203568Z","caller":"traceutil/trace.go:171","msg":"trace[1575300235] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"680.408713ms","start":"2026-02-18T14:43:58.523147Z","end":"2026-02-18T14:43:59.203556Z","steps":["trace[1575300235] 'process raft request' (duration: 679.486881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:59.203644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:43:58.523135Z","time spent":"680.457075ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:03.915634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.659837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:03.915706Z","caller":"traceutil/trace.go:171","msg":"trace[1935281209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"779.79093ms","start":"2026-02-18T14:44:03.135896Z","end":"2026-02-18T14:44:03.915687Z","steps":["trace[1935281209] 'range keys from in-memory index tree' (duration: 779.580445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:03.915744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.135879Z","time spent":"779.857721ms","remote":"127.0.0.1:40964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:44:03.915995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.096956ms","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-18T14:44:03.916088Z","caller":"traceutil/trace.go:171","msg":"trace[1026773671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"559.306781ms","start":"2026-02-18T14:44:03.356758Z","end":"2026-02-18T14:44:03.916065Z","steps":["trace[1026773671] 'range keys from in-memory index tree' (duration: 558.875551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:03.916136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.356739Z","time spent":"559.385513ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:44:03.916176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.221842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T14:44:03.916208Z","caller":"traceutil/trace.go:171","msg":"trace[2024571291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"557.277682ms","start":"2026-02-18T14:44:03.358922Z","end":"2026-02-18T14:44:03.9162Z","steps":["trace[2024571291] 'range keys from in-memory index tree' (duration: 557.044877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:03.916231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.35891Z","time spent":"557.315353ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:44:03.916464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.70229ms","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-18T14:44:03.916505Z","caller":"traceutil/trace.go:171","msg":"trace[575788670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:538; }","duration":"699.771431ms","start":"2026-02-18T14:44:03.216718Z","end":"2026-02-18T14:44:03.916489Z","steps":["trace[575788670] 'range keys from in-memory index tree' (duration: 699.570107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:03.91654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.216699Z","time spent":"699.834544ms","remote":"127.0.0.1:41212","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-18T14:44:03.916746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.725874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T14:44:03.916774Z","caller":"traceutil/trace.go:171","msg":"trace[1041019340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"678.790225ms","start":"2026-02-18T14:44:03.237976Z","end":"2026-02-18T14:44:03.916766Z","steps":["trace[1041019340] 'range keys from in-memory index tree' (duration: 678.631352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:03.916794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.237954Z","time spent":"678.836357ms","remote":"127.0.0.1:41212","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-18T14:44:04.449004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810442206930058,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:44:04.95011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810442206930058,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:44:05.184812Z","caller":"traceutil/trace.go:171","msg":"trace[1394914061] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.259446083s","start":"2026-02-18T14:44:03.925345Z","end":"2026-02-18T14:44:05.184791Z","steps":["trace[1394914061] 'process raft request' (duration: 1.259296879s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:05.184956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.925323Z","time spent":"1.259558585s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:05.333118Z","caller":"traceutil/trace.go:171","msg":"trace[761668598] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.403693016s","start":"2026-02-18T14:44:03.929405Z","end":"2026-02-18T14:44:05.333098Z","steps":["trace[761668598] 'process raft request' (duration: 1.403637904s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:05.333265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.9294Z","time spent":"1.403776028s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:05.333445Z","caller":"traceutil/trace.go:171","msg":"trace[2031735142] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.404083985s","start":"2026-02-18T14:44:03.929352Z","end":"2026-02-18T14:44:05.333436Z","steps":["trace[2031735142] 'process raft request' (duration: 1.352028015s)","trace[2031735142] 'compare' (duration: 51.578938ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:05.333451Z","caller":"traceutil/trace.go:171","msg":"trace[1297593431] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.403975513s","start":"2026-02-18T14:44:03.929455Z","end":"2026-02-18T14:44:05.33343Z","steps":["trace[1297593431] 'process raft request' (duration: 1.403616364s)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:05.333685Z","caller":"traceutil/trace.go:171","msg":"trace[2078121600] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:553; }","duration":"1.385588748s","start":"2026-02-18T14:44:03.948089Z","end":"2026-02-18T14:44:05.333677Z","steps":["trace[2078121600] 'read index received' (duration: 1.237342958s)","trace[2078121600] 'applied index is now lower than readState.Index' (duration: 148.24521ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:05.333816Z","caller":"traceutil/trace.go:171","msg":"trace[1540784281] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.022012074s","start":"2026-02-18T14:44:04.311798Z","end":"2026-02-18T14:44:05.33381Z","steps":["trace[1540784281] 'process raft request' (duration: 1.021553693s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:05.333853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:04.311762Z","time spent":"1.022069115s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:05.33399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385899695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g74vj\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-18T14:44:05.334009Z","caller":"traceutil/trace.go:171","msg":"trace[617184341] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g74vj; range_end:; response_count:1; response_revision:543; }","duration":"1.385940745s","start":"2026-02-18T14:44:03.948062Z","end":"2026-02-18T14:44:05.334003Z","steps":["trace[617184341] 'agreement among raft nodes before linearized reading' (duration: 1.385861693s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:05.33403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.948052Z","time spent":"1.385973958s","remote":"127.0.0.1:41134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-g74vj\" "} {"level":"warn","ts":"2026-02-18T14:44:05.334151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.606711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:44:05.334167Z","caller":"traceutil/trace.go:171","msg":"trace[1560791900] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:543; }","duration":"602.661202ms","start":"2026-02-18T14:44:04.731501Z","end":"2026-02-18T14:44:05.334162Z","steps":["trace[1560791900] 'agreement among raft nodes before linearized reading' (duration: 602.615011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:05.334182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:04.731477Z","time spent":"602.702104ms","remote":"127.0.0.1:40980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-02-18T14:44:05.33433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.347301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:05.334347Z","caller":"traceutil/trace.go:171","msg":"trace[1159841956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:543; }","duration":"200.401132ms","start":"2026-02-18T14:44:05.133941Z","end":"2026-02-18T14:44:05.334342Z","steps":["trace[1159841956] 'agreement among raft nodes before linearized reading' (duration: 200.375362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:05.33348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.929344Z","time spent":"1.404117256s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:05.333593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:03.92945Z","time spent":"1.404066995s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:05.592388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.319182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:44:05.592568Z","caller":"traceutil/trace.go:171","msg":"trace[1291301961] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"227.972871ms","start":"2026-02-18T14:44:05.364576Z","end":"2026-02-18T14:44:05.592549Z","steps":["trace[1291301961] 'process raft request' (duration: 105.404763ms)","trace[1291301961] 'compare' (duration: 122.161998ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:07.554444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.485291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-18T14:44:07.554512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.96969ms","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-18T14:44:07.554515Z","caller":"traceutil/trace.go:171","msg":"trace[119384152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"216.592723ms","start":"2026-02-18T14:44:07.337906Z","end":"2026-02-18T14:44:07.554498Z","steps":["trace[119384152] 'range keys from in-memory index tree' (duration: 216.345988ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:07.554559Z","caller":"traceutil/trace.go:171","msg":"trace[217286946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"214.018581ms","start":"2026-02-18T14:44:07.340526Z","end":"2026-02-18T14:44:07.554545Z","steps":["trace[217286946] 'range keys from in-memory index tree' (duration: 213.885498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:07.554704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.153191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T14:44:07.554728Z","caller":"traceutil/trace.go:171","msg":"trace[1096188421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"213.187042ms","start":"2026-02-18T14:44:07.341533Z","end":"2026-02-18T14:44:07.55472Z","steps":["trace[1096188421] 'range keys from in-memory index tree' (duration: 213.093469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:07.554799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.412781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T14:44:07.554829Z","caller":"traceutil/trace.go:171","msg":"trace[1423305033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"366.469423ms","start":"2026-02-18T14:44:07.188349Z","end":"2026-02-18T14:44:07.554818Z","steps":["trace[1423305033] 'range keys from in-memory index tree' (duration: 366.307959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:07.554855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:07.188334Z","time spent":"366.514314ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T14:44:07.683293Z","caller":"traceutil/trace.go:171","msg":"trace[1744837600] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"122.321222ms","start":"2026-02-18T14:44:07.560952Z","end":"2026-02-18T14:44:07.683273Z","steps":["trace[1744837600] 'process raft request' (duration: 122.20428ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:07.708826Z","caller":"traceutil/trace.go:171","msg":"trace[606126844] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"147.758638ms","start":"2026-02-18T14:44:07.561039Z","end":"2026-02-18T14:44:07.708797Z","steps":["trace[606126844] 'process raft request' (duration: 147.141824ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:07.709033Z","caller":"traceutil/trace.go:171","msg":"trace[1723222626] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"147.950053ms","start":"2026-02-18T14:44:07.561071Z","end":"2026-02-18T14:44:07.709021Z","steps":["trace[1723222626] 'process raft request' (duration: 147.245986ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:07.709133Z","caller":"traceutil/trace.go:171","msg":"trace[1495800272] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"147.339277ms","start":"2026-02-18T14:44:07.561786Z","end":"2026-02-18T14:44:07.709125Z","steps":["trace[1495800272] 'process raft request' (duration: 146.562759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:14.24885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810442206930260,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:44:14.540683Z","caller":"traceutil/trace.go:171","msg":"trace[842234410] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"793.670056ms","start":"2026-02-18T14:44:13.746997Z","end":"2026-02-18T14:44:14.540667Z","steps":["trace[842234410] 'process raft request' (duration: 793.520032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:14.540802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:13.746988Z","time spent":"793.752888ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:14.73915Z","caller":"traceutil/trace.go:171","msg":"trace[1546464406] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"991.2681ms","start":"2026-02-18T14:44:13.747863Z","end":"2026-02-18T14:44:14.739132Z","steps":["trace[1546464406] 'read index received' (duration: 792.761474ms)","trace[1546464406] 'applied index is now lower than readState.Index' (duration: 198.505866ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:14.739243Z","caller":"traceutil/trace.go:171","msg":"trace[195837807] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"991.611339ms","start":"2026-02-18T14:44:13.747609Z","end":"2026-02-18T14:44:14.73922Z","steps":["trace[195837807] 'process raft request' (duration: 971.091413ms)","trace[195837807] 'compare' (duration: 20.309181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:14.739299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.416475ms","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-18T14:44:14.739318Z","caller":"traceutil/trace.go:171","msg":"trace[494437247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"991.459296ms","start":"2026-02-18T14:44:13.747854Z","end":"2026-02-18T14:44:14.739313Z","steps":["trace[494437247] 'agreement among raft nodes before linearized reading' (duration: 991.363943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:14.739337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:13.74785Z","time spent":"991.483616ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:44:14.739365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:13.747604Z","time spent":"991.692721ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:14.739518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.374203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T14:44:14.73954Z","caller":"traceutil/trace.go:171","msg":"trace[1940486262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"991.413224ms","start":"2026-02-18T14:44:13.74812Z","end":"2026-02-18T14:44:14.739533Z","steps":["trace[1940486262] 'agreement among raft nodes before linearized reading' (duration: 991.337122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:14.739558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:13.748106Z","time spent":"991.447855ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:44:14.739694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.466427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T14:44:14.739711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.488768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:14.739729Z","caller":"traceutil/trace.go:171","msg":"trace[146866753] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:646; }","duration":"880.546059ms","start":"2026-02-18T14:44:13.859178Z","end":"2026-02-18T14:44:14.73971Z","steps":["trace[146866753] 'agreement among raft nodes before linearized reading' (duration: 880.497978ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:14.73973Z","caller":"traceutil/trace.go:171","msg":"trace[1747172292] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:646; }","duration":"880.528879ms","start":"2026-02-18T14:44:13.859195Z","end":"2026-02-18T14:44:14.739724Z","steps":["trace[1747172292] 'agreement among raft nodes before linearized reading' (duration: 880.499769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:14.739747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:13.859164Z","time spent":"880.578131ms","remote":"127.0.0.1:54208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-02-18T14:44:14.739752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:13.859169Z","time spent":"880.57837ms","remote":"127.0.0.1:54208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-02-18T14:44:14.739857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.837488ms","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-18T14:44:14.739871Z","caller":"traceutil/trace.go:171","msg":"trace[1556240971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:646; }","duration":"434.878699ms","start":"2026-02-18T14:44:14.304988Z","end":"2026-02-18T14:44:14.739867Z","steps":["trace[1556240971] 'agreement among raft nodes before linearized reading' (duration: 434.836618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:14.739883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:14.304971Z","time spent":"434.9096ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T14:44:14.739911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.095305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:14.739931Z","caller":"traceutil/trace.go:171","msg":"trace[720613736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"606.145616ms","start":"2026-02-18T14:44:14.133779Z","end":"2026-02-18T14:44:14.739925Z","steps":["trace[720613736] 'agreement among raft nodes before linearized reading' (duration: 606.112995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:14.739979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:14.133761Z","time spent":"606.212057ms","remote":"127.0.0.1:40964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T14:44:23.000114Z","caller":"traceutil/trace.go:171","msg":"trace[1970801403] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"430.063943ms","start":"2026-02-18T14:44:22.57003Z","end":"2026-02-18T14:44:23.000094Z","steps":["trace[1970801403] 'process raft request' (duration: 429.93906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:23.000234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:22.570015Z","time spent":"430.154285ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:23.001294Z","caller":"traceutil/trace.go:171","msg":"trace[875311708] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:734; }","duration":"224.70137ms","start":"2026-02-18T14:44:22.776565Z","end":"2026-02-18T14:44:23.001266Z","steps":["trace[875311708] 'read index received' (duration: 223.486651ms)","trace[875311708] 'applied index is now lower than readState.Index' (duration: 1.214059ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:23.001334Z","caller":"traceutil/trace.go:171","msg":"trace[812342154] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"382.06912ms","start":"2026-02-18T14:44:22.619255Z","end":"2026-02-18T14:44:23.001324Z","steps":["trace[812342154] 'process raft request' (duration: 381.920816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:23.001382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:22.619239Z","time spent":"382.119211ms","remote":"127.0.0.1:41018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:44:23.0015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.921315ms","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-18T14:44:23.001543Z","caller":"traceutil/trace.go:171","msg":"trace[254470034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"225.001108ms","start":"2026-02-18T14:44:22.776533Z","end":"2026-02-18T14:44:23.001534Z","steps":["trace[254470034] 'agreement among raft nodes before linearized reading' (duration: 224.852594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:23.001647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.141974ms","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-18T14:44:23.001742Z","caller":"traceutil/trace.go:171","msg":"trace[1451509445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:717; }","duration":"221.242796ms","start":"2026-02-18T14:44:22.780489Z","end":"2026-02-18T14:44:23.001732Z","steps":["trace[1451509445] 'agreement among raft nodes before linearized reading' (duration: 221.068642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:23.00185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.51685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T14:44:23.001872Z","caller":"traceutil/trace.go:171","msg":"trace[1979378668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"218.551992ms","start":"2026-02-18T14:44:22.783315Z","end":"2026-02-18T14:44:23.001867Z","steps":["trace[1979378668] 'agreement among raft nodes before linearized reading' (duration: 218.49107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:23.001953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.883397ms","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-18T14:44:23.001969Z","caller":"traceutil/trace.go:171","msg":"trace[1406327019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:717; }","duration":"220.909478ms","start":"2026-02-18T14:44:22.781055Z","end":"2026-02-18T14:44:23.001964Z","steps":["trace[1406327019] 'agreement among raft nodes before linearized reading' (duration: 220.861287ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:23.108608Z","caller":"traceutil/trace.go:171","msg":"trace[200949913] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"100.126603ms","start":"2026-02-18T14:44:23.008459Z","end":"2026-02-18T14:44:23.108585Z","steps":["trace[200949913] 'process raft request' (duration: 100.054822ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:23.109242Z","caller":"traceutil/trace.go:171","msg":"trace[97194252] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"102.132004ms","start":"2026-02-18T14:44:23.007095Z","end":"2026-02-18T14:44:23.109227Z","steps":["trace[97194252] 'process raft request' (duration: 101.341364ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:23.110087Z","caller":"traceutil/trace.go:171","msg":"trace[62928908] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"103.039865ms","start":"2026-02-18T14:44:23.007031Z","end":"2026-02-18T14:44:23.110071Z","steps":["trace[62928908] 'process raft request' (duration: 75.029876ms)","trace[62928908] 'compare' (duration: 26.229545ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:26.554818Z","caller":"traceutil/trace.go:171","msg":"trace[1174806772] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"423.663897ms","start":"2026-02-18T14:44:26.131135Z","end":"2026-02-18T14:44:26.554799Z","steps":["trace[1174806772] 'read index received' (duration: 423.527884ms)","trace[1174806772] 'applied index is now lower than readState.Index' (duration: 135.353µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:26.554918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.7653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:26.554938Z","caller":"traceutil/trace.go:171","msg":"trace[1400465761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"423.825911ms","start":"2026-02-18T14:44:26.131106Z","end":"2026-02-18T14:44:26.554932Z","steps":["trace[1400465761] 'agreement among raft nodes before linearized reading' (duration: 423.76319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:26.55496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:26.131092Z","time spent":"423.862812ms","remote":"127.0.0.1:40964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T14:44:26.555214Z","caller":"traceutil/trace.go:171","msg":"trace[558261905] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"671.971119ms","start":"2026-02-18T14:44:25.883233Z","end":"2026-02-18T14:44:26.555204Z","steps":["trace[558261905] 'process raft request' (duration: 671.473507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:26.555294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:25.883219Z","time spent":"672.02691ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:27.380507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.048398ms","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-18T14:44:27.380562Z","caller":"traceutil/trace.go:171","msg":"trace[345008589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"360.145951ms","start":"2026-02-18T14:44:27.020403Z","end":"2026-02-18T14:44:27.380549Z","steps":["trace[345008589] 'range keys from in-memory index tree' (duration: 359.886375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:27.380588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:27.020385Z","time spent":"360.197472ms","remote":"127.0.0.1:41212","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-18T14:44:27.380728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.638659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T14:44:27.380745Z","caller":"traceutil/trace.go:171","msg":"trace[1161544962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"326.684241ms","start":"2026-02-18T14:44:27.054055Z","end":"2026-02-18T14:44:27.380739Z","steps":["trace[1161544962] 'range keys from in-memory index tree' (duration: 326.518567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:27.38076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:27.05398Z","time spent":"326.777022ms","remote":"127.0.0.1:41212","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-18T14:44:27.381091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.271149ms","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-18T14:44:27.381119Z","caller":"traceutil/trace.go:171","msg":"trace[1832090050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"202.32422ms","start":"2026-02-18T14:44:27.178787Z","end":"2026-02-18T14:44:27.381111Z","steps":["trace[1832090050] 'range keys from in-memory index tree' (duration: 202.193017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:27.381389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.581681ms","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-18T14:44:27.381418Z","caller":"traceutil/trace.go:171","msg":"trace[886234322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"170.628321ms","start":"2026-02-18T14:44:27.210781Z","end":"2026-02-18T14:44:27.381409Z","steps":["trace[886234322] 'range keys from in-memory index tree' (duration: 170.513049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:27.381812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.369392ms","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-18T14:44:27.381895Z","caller":"traceutil/trace.go:171","msg":"trace[1171570965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"169.462774ms","start":"2026-02-18T14:44:27.212422Z","end":"2026-02-18T14:44:27.381885Z","steps":["trace[1171570965] 'range keys from in-memory index tree' (duration: 169.071614ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:27.777805Z","caller":"traceutil/trace.go:171","msg":"trace[1511420388] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"386.262591ms","start":"2026-02-18T14:44:27.391511Z","end":"2026-02-18T14:44:27.777774Z","steps":["trace[1511420388] 'process raft request' (duration: 323.83949ms)","trace[1511420388] 'compare' (duration: 61.97204ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:27.777982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:27.391492Z","time spent":"386.399555ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:27.777982Z","caller":"traceutil/trace.go:171","msg":"trace[318526736] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"385.101273ms","start":"2026-02-18T14:44:27.392862Z","end":"2026-02-18T14:44:27.777964Z","steps":["trace[318526736] 'process raft request' (duration: 384.825236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:27.778096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:27.392857Z","time spent":"385.175714ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:27.778248Z","caller":"traceutil/trace.go:171","msg":"trace[906670314] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"384.648392ms","start":"2026-02-18T14:44:27.393589Z","end":"2026-02-18T14:44:27.778237Z","steps":["trace[906670314] 'process raft request' (duration: 384.280963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:27.77828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:27.393581Z","time spent":"384.681593ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:27.778358Z","caller":"traceutil/trace.go:171","msg":"trace[647456959] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:749; }","duration":"334.137829ms","start":"2026-02-18T14:44:27.444214Z","end":"2026-02-18T14:44:27.778351Z","steps":["trace[647456959] 'read index received' (duration: 32.61875ms)","trace[647456959] 'applied index is now lower than readState.Index' (duration: 301.518119ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:27.778401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.1848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:27.778422Z","caller":"traceutil/trace.go:171","msg":"trace[407406016] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:733; }","duration":"334.236741ms","start":"2026-02-18T14:44:27.444178Z","end":"2026-02-18T14:44:27.778415Z","steps":["trace[407406016] 'agreement among raft nodes before linearized reading' (duration: 334.19436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:27.778443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:27.444162Z","time spent":"334.275522ms","remote":"127.0.0.1:41096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-18T14:44:27.778556Z","caller":"traceutil/trace.go:171","msg":"trace[1680012600] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"383.518294ms","start":"2026-02-18T14:44:27.395032Z","end":"2026-02-18T14:44:27.77855Z","steps":["trace[1680012600] 'process raft request' (duration: 383.183856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:27.778593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:27.395024Z","time spent":"383.547134ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:42.277014Z","caller":"traceutil/trace.go:171","msg":"trace[970730965] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"450.904596ms","start":"2026-02-18T14:44:41.826094Z","end":"2026-02-18T14:44:42.276998Z","steps":["trace[970730965] 'read index received' (duration: 450.713991ms)","trace[970730965] 'applied index is now lower than readState.Index' (duration: 190.035µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:42.277092Z","caller":"traceutil/trace.go:171","msg":"trace[129024320] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"451.576132ms","start":"2026-02-18T14:44:41.825511Z","end":"2026-02-18T14:44:42.277087Z","steps":["trace[129024320] 'process raft request' (duration: 451.375037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:42.277164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:41.825492Z","time spent":"451.617204ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:42.277314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.218643ms","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-18T14:44:42.277331Z","caller":"traceutil/trace.go:171","msg":"trace[1362478501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"451.247735ms","start":"2026-02-18T14:44:41.826078Z","end":"2026-02-18T14:44:42.277326Z","steps":["trace[1362478501] 'agreement among raft nodes before linearized reading' (duration: 451.175003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:42.277344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:41.826071Z","time spent":"451.270895ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:44:42.277466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.363404ms","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-18T14:44:42.277517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.623673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:42.277534Z","caller":"traceutil/trace.go:171","msg":"trace[1343468128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"146.659834ms","start":"2026-02-18T14:44:42.13087Z","end":"2026-02-18T14:44:42.277529Z","steps":["trace[1343468128] 'agreement among raft nodes before linearized reading' (duration: 146.635263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:42.277559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.184295ms","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-18T14:44:42.277576Z","caller":"traceutil/trace.go:171","msg":"trace[490063879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"415.209417ms","start":"2026-02-18T14:44:41.86236Z","end":"2026-02-18T14:44:42.27757Z","steps":["trace[490063879] 'agreement among raft nodes before linearized reading' (duration: 415.168326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:42.277595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:41.862356Z","time spent":"415.235607ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T14:44:42.277515Z","caller":"traceutil/trace.go:171","msg":"trace[1251126983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"428.421675ms","start":"2026-02-18T14:44:41.849077Z","end":"2026-02-18T14:44:42.277499Z","steps":["trace[1251126983] 'agreement among raft nodes before linearized reading' (duration: 428.301992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:42.277622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:41.849072Z","time spent":"428.544888ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T14:44:43.80115Z","caller":"traceutil/trace.go:171","msg":"trace[787556480] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"228.335722ms","start":"2026-02-18T14:44:43.572778Z","end":"2026-02-18T14:44:43.801114Z","steps":["trace[787556480] 'process raft request' (duration: 228.180538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:44.139386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.865513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:44.139464Z","caller":"traceutil/trace.go:171","msg":"trace[836088855] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:779; }","duration":"104.016026ms","start":"2026-02-18T14:44:44.035433Z","end":"2026-02-18T14:44:44.139449Z","steps":["trace[836088855] 'count revisions from in-memory index tree' (duration: 103.784921ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:44.607081Z","caller":"traceutil/trace.go:171","msg":"trace[1706170418] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"270.646243ms","start":"2026-02-18T14:44:44.336409Z","end":"2026-02-18T14:44:44.607055Z","steps":["trace[1706170418] 'read index received' (duration: 270.50527ms)","trace[1706170418] 'applied index is now lower than readState.Index' (duration: 139.963µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:44.607343Z","caller":"traceutil/trace.go:171","msg":"trace[407873579] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"322.474102ms","start":"2026-02-18T14:44:44.284855Z","end":"2026-02-18T14:44:44.607329Z","steps":["trace[407873579] 'process raft request' (duration: 322.074152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:44.607455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:44.284844Z","time spent":"322.537733ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:44.607675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.263698ms","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-18T14:44:44.607711Z","caller":"traceutil/trace.go:171","msg":"trace[1101922100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"271.33694ms","start":"2026-02-18T14:44:44.336364Z","end":"2026-02-18T14:44:44.607701Z","steps":["trace[1101922100] 'agreement among raft nodes before linearized reading' (duration: 271.197846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:44.607833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.907896ms","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-18T14:44:44.607862Z","caller":"traceutil/trace.go:171","msg":"trace[729735053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"257.987257ms","start":"2026-02-18T14:44:44.349868Z","end":"2026-02-18T14:44:44.607855Z","steps":["trace[729735053] 'agreement among raft nodes before linearized reading' (duration: 257.915226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:44.607982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.428164ms","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-18T14:44:44.608011Z","caller":"traceutil/trace.go:171","msg":"trace[634217127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"257.468085ms","start":"2026-02-18T14:44:44.350536Z","end":"2026-02-18T14:44:44.608004Z","steps":["trace[634217127] 'agreement among raft nodes before linearized reading' (duration: 257.393413ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:45.251296Z","caller":"traceutil/trace.go:171","msg":"trace[317876667] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"639.260696ms","start":"2026-02-18T14:44:44.612006Z","end":"2026-02-18T14:44:45.251267Z","steps":["trace[317876667] 'process raft request' (duration: 563.765336ms)","trace[317876667] 'compare' (duration: 75.324026ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:45.252112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:44.611991Z","time spent":"640.043215ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:45.50074Z","caller":"traceutil/trace.go:171","msg":"trace[378653413] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:804; }","duration":"767.134238ms","start":"2026-02-18T14:44:44.733585Z","end":"2026-02-18T14:44:45.500719Z","steps":["trace[378653413] 'read index received' (duration: 442.033613ms)","trace[378653413] 'applied index is now lower than readState.Index' (duration: 325.099534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:45.500999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.398084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:44:45.501032Z","caller":"traceutil/trace.go:171","msg":"trace[861519160] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:783; }","duration":"767.486936ms","start":"2026-02-18T14:44:44.733536Z","end":"2026-02-18T14:44:45.501023Z","steps":["trace[861519160] 'agreement among raft nodes before linearized reading' (duration: 767.332632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:45.501063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:44.733493Z","time spent":"767.562327ms","remote":"127.0.0.1:40980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"info","ts":"2026-02-18T14:44:45.50134Z","caller":"traceutil/trace.go:171","msg":"trace[66036003] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"876.064312ms","start":"2026-02-18T14:44:44.625265Z","end":"2026-02-18T14:44:45.501329Z","steps":["trace[66036003] 'process raft request' (duration: 873.402168ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:45.502652Z","caller":"traceutil/trace.go:171","msg":"trace[1477287987] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"877.327382ms","start":"2026-02-18T14:44:44.62531Z","end":"2026-02-18T14:44:45.502637Z","steps":["trace[1477287987] 'process raft request' (duration: 875.365405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:45.502733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:44.625307Z","time spent":"877.387783ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:45.501413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:44.625253Z","time spent":"876.111883ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:45.71112Z","caller":"traceutil/trace.go:171","msg":"trace[354201178] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"126.620851ms","start":"2026-02-18T14:44:45.584477Z","end":"2026-02-18T14:44:45.711098Z","steps":["trace[354201178] 'process raft request' (duration: 99.856706ms)","trace[354201178] 'compare' (duration: 26.653513ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:56.875247Z","caller":"traceutil/trace.go:171","msg":"trace[655678146] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"219.972511ms","start":"2026-02-18T14:44:56.655256Z","end":"2026-02-18T14:44:56.875228Z","steps":["trace[655678146] 'process raft request' (duration: 219.841718ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:57.845013Z","caller":"traceutil/trace.go:171","msg":"trace[389146195] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"239.03713ms","start":"2026-02-18T14:44:57.60596Z","end":"2026-02-18T14:44:57.844997Z","steps":["trace[389146195] 'read index received' (duration: 238.910387ms)","trace[389146195] 'applied index is now lower than readState.Index' (duration: 126.213µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:57.845262Z","caller":"traceutil/trace.go:171","msg":"trace[1591683330] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"243.807915ms","start":"2026-02-18T14:44:57.601447Z","end":"2026-02-18T14:44:57.845255Z","steps":["trace[1591683330] 'process raft request' (duration: 243.464647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:57.845714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.562663ms","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-18T14:44:57.845738Z","caller":"traceutil/trace.go:171","msg":"trace[1336786772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:867; }","duration":"239.789518ms","start":"2026-02-18T14:44:57.60594Z","end":"2026-02-18T14:44:57.84573Z","steps":["trace[1336786772] 'agreement among raft nodes before linearized reading' (duration: 239.527911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:57.84585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.29442ms","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-18T14:44:57.845865Z","caller":"traceutil/trace.go:171","msg":"trace[1348993191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:867; }","duration":"208.330602ms","start":"2026-02-18T14:44:57.63753Z","end":"2026-02-18T14:44:57.84586Z","steps":["trace[1348993191] 'agreement among raft nodes before linearized reading' (duration: 208.28549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:58.449474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810442206930767,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:44:59.414237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.158712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:44:59.414298Z","caller":"traceutil/trace.go:171","msg":"trace[1636160995] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"1.565878916s","start":"2026-02-18T14:44:57.848409Z","end":"2026-02-18T14:44:59.414288Z","steps":["trace[1636160995] 'process raft request' (duration: 697.619612ms)","trace[1636160995] 'compare' (duration: 867.961947ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:59.414328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:57.848398Z","time spent":"1.565917867s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:59.420318Z","caller":"traceutil/trace.go:171","msg":"trace[1471786062] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:896; }","duration":"1.471245566s","start":"2026-02-18T14:44:57.949062Z","end":"2026-02-18T14:44:59.420308Z","steps":["trace[1471786062] 'read index received' (duration: 596.977446ms)","trace[1471786062] 'applied index is now lower than readState.Index' (duration: 874.26764ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:44:59.420447Z","caller":"traceutil/trace.go:171","msg":"trace[2098318123] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"1.571317797s","start":"2026-02-18T14:44:57.849119Z","end":"2026-02-18T14:44:59.420436Z","steps":["trace[2098318123] 'process raft request' (duration: 1.571144523s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:59.42049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:57.849111Z","time spent":"1.571352058s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:44:59.420622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.471564343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T14:44:59.420637Z","caller":"traceutil/trace.go:171","msg":"trace[1397756600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"1.471602575s","start":"2026-02-18T14:44:57.949029Z","end":"2026-02-18T14:44:59.420632Z","steps":["trace[1397756600] 'agreement among raft nodes before linearized reading' (duration: 1.471533373s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:59.42065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:57.949016Z","time spent":"1.471631735s","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T14:44:59.47558Z","caller":"traceutil/trace.go:171","msg":"trace[1127742772] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"719.691754ms","start":"2026-02-18T14:44:58.755873Z","end":"2026-02-18T14:44:59.475564Z","steps":["trace[1127742772] 'process raft request' (duration: 719.611032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:59.475664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34446893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T14:44:59.475695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:58.755856Z","time spent":"719.768525ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:44:59.475706Z","caller":"traceutil/trace.go:171","msg":"trace[1105218476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"1.344539482s","start":"2026-02-18T14:44:58.131155Z","end":"2026-02-18T14:44:59.475695Z","steps":["trace[1105218476] 'agreement among raft nodes before linearized reading' (duration: 1.344418199s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:59.475732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:58.131137Z","time spent":"1.344589723s","remote":"127.0.0.1:40964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:44:59.475894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.397855ms","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-18T14:44:59.475913Z","caller":"traceutil/trace.go:171","msg":"trace[327826084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:870; }","duration":"177.414965ms","start":"2026-02-18T14:44:59.298491Z","end":"2026-02-18T14:44:59.475906Z","steps":["trace[327826084] 'agreement among raft nodes before linearized reading' (duration: 177.343903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:59.475978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.011867ms","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-18T14:44:59.476002Z","caller":"traceutil/trace.go:171","msg":"trace[1581298878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:870; }","duration":"597.058188ms","start":"2026-02-18T14:44:58.878934Z","end":"2026-02-18T14:44:59.475992Z","steps":["trace[1581298878] 'agreement among raft nodes before linearized reading' (duration: 596.982936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:59.476017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:44:58.878907Z","time spent":"597.10642ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T14:44:59.634201Z","caller":"traceutil/trace.go:171","msg":"trace[1302990184] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"211.072726ms","start":"2026-02-18T14:44:59.423114Z","end":"2026-02-18T14:44:59.634187Z","steps":["trace[1302990184] 'process raft request' (duration: 210.979784ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:59.634495Z","caller":"traceutil/trace.go:171","msg":"trace[2144298236] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:900; }","duration":"156.509172ms","start":"2026-02-18T14:44:59.47798Z","end":"2026-02-18T14:44:59.634489Z","steps":["trace[2144298236] 'read index received' (duration: 156.507332ms)","trace[2144298236] 'applied index is now lower than readState.Index' (duration: 1.51µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:44:59.63455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.557093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:44:59.634564Z","caller":"traceutil/trace.go:171","msg":"trace[1491634886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:871; }","duration":"156.599034ms","start":"2026-02-18T14:44:59.47796Z","end":"2026-02-18T14:44:59.63456Z","steps":["trace[1491634886] 'agreement among raft nodes before linearized reading' (duration: 156.564403ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:59.707449Z","caller":"traceutil/trace.go:171","msg":"trace[1311617525] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"227.366209ms","start":"2026-02-18T14:44:59.480063Z","end":"2026-02-18T14:44:59.707429Z","steps":["trace[1311617525] 'process raft request' (duration: 225.35081ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:44:59.707641Z","caller":"traceutil/trace.go:171","msg":"trace[1235304934] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"226.680082ms","start":"2026-02-18T14:44:59.480941Z","end":"2026-02-18T14:44:59.707621Z","steps":["trace[1235304934] 'process raft request' (duration: 226.274133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:44:59.707826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.06329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:44:59.707856Z","caller":"traceutil/trace.go:171","msg":"trace[486474053] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:873; }","duration":"195.127352ms","start":"2026-02-18T14:44:59.51272Z","end":"2026-02-18T14:44:59.707847Z","steps":["trace[486474053] 'agreement among raft nodes before linearized reading' (duration: 195.024299ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:07.685498Z","caller":"traceutil/trace.go:171","msg":"trace[336513537] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"158.411919ms","start":"2026-02-18T14:45:07.527076Z","end":"2026-02-18T14:45:07.685488Z","steps":["trace[336513537] 'read index received' (duration: 158.304316ms)","trace[336513537] 'applied index is now lower than readState.Index' (duration: 107.093µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:07.68565Z","caller":"traceutil/trace.go:171","msg":"trace[940863750] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"192.871268ms","start":"2026-02-18T14:45:07.492772Z","end":"2026-02-18T14:45:07.685644Z","steps":["trace[940863750] 'process raft request' (duration: 192.639143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:07.68578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.696125ms","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-18T14:45:07.685801Z","caller":"traceutil/trace.go:171","msg":"trace[621165739] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"158.747406ms","start":"2026-02-18T14:45:07.527049Z","end":"2026-02-18T14:45:07.685796Z","steps":["trace[621165739] 'agreement among raft nodes before linearized reading' (duration: 158.681554ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:14.275358Z","caller":"traceutil/trace.go:171","msg":"trace[120962239] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"463.227363ms","start":"2026-02-18T14:45:13.812101Z","end":"2026-02-18T14:45:14.275328Z","steps":["trace[120962239] 'read index received' (duration: 462.921176ms)","trace[120962239] 'applied index is now lower than readState.Index' (duration: 305.187µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:14.275679Z","caller":"traceutil/trace.go:171","msg":"trace[131684222] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"490.319116ms","start":"2026-02-18T14:45:13.785344Z","end":"2026-02-18T14:45:14.275663Z","steps":["trace[131684222] 'process raft request' (duration: 489.755653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:14.275785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:13.785336Z","time spent":"490.378817ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:14.276072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.970802ms","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-18T14:45:14.276123Z","caller":"traceutil/trace.go:171","msg":"trace[529890816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:907; }","duration":"464.038863ms","start":"2026-02-18T14:45:13.812074Z","end":"2026-02-18T14:45:14.276113Z","steps":["trace[529890816] 'agreement among raft nodes before linearized reading' (duration: 463.91008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:14.276151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:13.812062Z","time spent":"464.081935ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T14:45:14.276888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.629811ms","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-18T14:45:14.276929Z","caller":"traceutil/trace.go:171","msg":"trace[1705389943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"438.717993ms","start":"2026-02-18T14:45:13.838202Z","end":"2026-02-18T14:45:14.27692Z","steps":["trace[1705389943] 'agreement among raft nodes before linearized reading' (duration: 438.59262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:14.276963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:13.838189Z","time spent":"438.767354ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:45:14.277341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.429323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:45:14.2774Z","caller":"traceutil/trace.go:171","msg":"trace[2002068060] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:907; }","duration":"130.546516ms","start":"2026-02-18T14:45:14.14684Z","end":"2026-02-18T14:45:14.277386Z","steps":["trace[2002068060] 'agreement among raft nodes before linearized reading' (duration: 130.406613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:14.277594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.930223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:14.277613Z","caller":"traceutil/trace.go:171","msg":"trace[707957357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"144.978874ms","start":"2026-02-18T14:45:14.132628Z","end":"2026-02-18T14:45:14.277607Z","steps":["trace[707957357] 'agreement among raft nodes before linearized reading' (duration: 144.948604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:14.277781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.638126ms","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-18T14:45:14.277797Z","caller":"traceutil/trace.go:171","msg":"trace[1530591745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"437.664017ms","start":"2026-02-18T14:45:13.840128Z","end":"2026-02-18T14:45:14.277792Z","steps":["trace[1530591745] 'agreement among raft nodes before linearized reading' (duration: 437.579195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:14.277814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:13.840122Z","time spent":"437.686857ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T14:45:14.27789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.292012ms","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-18T14:45:14.277904Z","caller":"traceutil/trace.go:171","msg":"trace[1608527083] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"438.317012ms","start":"2026-02-18T14:45:13.839582Z","end":"2026-02-18T14:45:14.277899Z","steps":["trace[1608527083] 'agreement among raft nodes before linearized reading' (duration: 438.272041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:14.277919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:13.839578Z","time spent":"438.337813ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T14:45:14.648543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.479111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:14.648989Z","caller":"traceutil/trace.go:171","msg":"trace[249067962] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"360.61732ms","start":"2026-02-18T14:45:14.288352Z","end":"2026-02-18T14:45:14.648969Z","steps":["trace[249067962] 'process raft request' (duration: 360.394315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:14.649077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:14.288343Z","time spent":"360.695303ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:45:14.649166Z","caller":"traceutil/trace.go:171","msg":"trace[1791966725] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"363.033459ms","start":"2026-02-18T14:45:14.286049Z","end":"2026-02-18T14:45:14.649083Z","steps":["trace[1791966725] 'process raft request' (duration: 100.916622ms)","trace[1791966725] 'compare' (duration: 261.331877ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:14.649361Z","caller":"traceutil/trace.go:171","msg":"trace[930302603] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"363.163892ms","start":"2026-02-18T14:45:14.286172Z","end":"2026-02-18T14:45:14.649336Z","steps":["trace[930302603] 'process raft request' (duration: 362.472846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:14.649423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:14.286138Z","time spent":"363.256574ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:14.650573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:14.286039Z","time spent":"363.289085ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:16.63286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.740761ms","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-18T14:45:16.632907Z","caller":"traceutil/trace.go:171","msg":"trace[1319365629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"264.818862ms","start":"2026-02-18T14:45:16.368078Z","end":"2026-02-18T14:45:16.632897Z","steps":["trace[1319365629] 'range keys from in-memory index tree' (duration: 264.638538ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:16.841106Z","caller":"traceutil/trace.go:171","msg":"trace[647766176] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"133.894937ms","start":"2026-02-18T14:45:16.707197Z","end":"2026-02-18T14:45:16.841092Z","steps":["trace[647766176] 'process raft request' (duration: 133.876886ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:16.842188Z","caller":"traceutil/trace.go:171","msg":"trace[1616219728] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"137.638847ms","start":"2026-02-18T14:45:16.704526Z","end":"2026-02-18T14:45:16.842165Z","steps":["trace[1616219728] 'process raft request' (duration: 45.23631ms)","trace[1616219728] 'compare' (duration: 91.223408ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:16.843385Z","caller":"traceutil/trace.go:171","msg":"trace[726699983] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"136.755146ms","start":"2026-02-18T14:45:16.705509Z","end":"2026-02-18T14:45:16.842264Z","steps":["trace[726699983] 'process raft request' (duration: 135.546836ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:22.767886Z","caller":"traceutil/trace.go:171","msg":"trace[1212341648] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"399.629021ms","start":"2026-02-18T14:45:22.368238Z","end":"2026-02-18T14:45:22.767867Z","steps":["trace[1212341648] 'process raft request' (duration: 399.256992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:22.768175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:22.368193Z","time spent":"399.832186ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:25.071622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.669195ms","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-18T14:45:25.071711Z","caller":"traceutil/trace.go:171","msg":"trace[1185128504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:972; }","duration":"151.799417ms","start":"2026-02-18T14:45:24.919894Z","end":"2026-02-18T14:45:25.071693Z","steps":["trace[1185128504] 'range keys from in-memory index tree' (duration: 151.413219ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:25.071712Z","caller":"traceutil/trace.go:171","msg":"trace[1102784797] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"148.942919ms","start":"2026-02-18T14:45:24.922739Z","end":"2026-02-18T14:45:25.071682Z","steps":["trace[1102784797] 'process raft request' (duration: 69.333851ms)","trace[1102784797] 'compare' (duration: 79.420474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:31.079058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.38348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:45:31.079151Z","caller":"traceutil/trace.go:171","msg":"trace[1902983058] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"369.232709ms","start":"2026-02-18T14:45:30.709899Z","end":"2026-02-18T14:45:31.079131Z","steps":["trace[1902983058] 'read index received' (duration: 95.442µs)","trace[1902983058] 'applied index is now lower than readState.Index' (duration: 369.136057ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:31.079343Z","caller":"traceutil/trace.go:171","msg":"trace[212188460] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"747.812771ms","start":"2026-02-18T14:45:30.331517Z","end":"2026-02-18T14:45:31.07933Z","steps":["trace[212188460] 'process raft request' (duration: 154.077743ms)","trace[212188460] 'compare' (duration: 592.945089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:45:31.079404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:30.331494Z","time spent":"747.876373ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:45:31.079705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.807733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:45:31.079739Z","caller":"traceutil/trace.go:171","msg":"trace[199676032] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:989; }","duration":"369.921385ms","start":"2026-02-18T14:45:30.709807Z","end":"2026-02-18T14:45:31.079728Z","steps":["trace[199676032] 'agreement among raft nodes before linearized reading' (duration: 369.846843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:31.079768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:30.709739Z","time spent":"370.021698ms","remote":"127.0.0.1:41404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-18T14:45:31.080001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.612162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-18T14:45:31.080029Z","caller":"traceutil/trace.go:171","msg":"trace[2041017100] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:989; }","duration":"358.639363ms","start":"2026-02-18T14:45:30.721381Z","end":"2026-02-18T14:45:31.080021Z","steps":["trace[2041017100] 'agreement among raft nodes before linearized reading' (duration: 358.53986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:31.080059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:45:30.721359Z","time spent":"358.693905ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T14:45:31.080411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.463904ms","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-18T14:45:31.080438Z","caller":"traceutil/trace.go:171","msg":"trace[1615995616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"169.556495ms","start":"2026-02-18T14:45:30.910873Z","end":"2026-02-18T14:45:31.08043Z","steps":["trace[1615995616] 'agreement among raft nodes before linearized reading' (duration: 169.487344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:31.080603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.767672ms","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-18T14:45:31.080629Z","caller":"traceutil/trace.go:171","msg":"trace[1176141784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"267.830234ms","start":"2026-02-18T14:45:30.812791Z","end":"2026-02-18T14:45:31.080621Z","steps":["trace[1176141784] 'agreement among raft nodes before linearized reading' (duration: 267.754462ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:31.318229Z","caller":"traceutil/trace.go:171","msg":"trace[1750342151] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"224.187973ms","start":"2026-02-18T14:45:31.094016Z","end":"2026-02-18T14:45:31.318204Z","steps":["trace[1750342151] 'process raft request' (duration: 222.098492ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:31.318804Z","caller":"traceutil/trace.go:171","msg":"trace[680674698] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"223.66727ms","start":"2026-02-18T14:45:31.095106Z","end":"2026-02-18T14:45:31.318773Z","steps":["trace[680674698] 'process raft request' (duration: 223.067136ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:31.320188Z","caller":"traceutil/trace.go:171","msg":"trace[1155760650] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1026; }","duration":"175.364747ms","start":"2026-02-18T14:45:31.144812Z","end":"2026-02-18T14:45:31.320177Z","steps":["trace[1155760650] 'read index received' (duration: 171.200466ms)","trace[1155760650] 'applied index is now lower than readState.Index' (duration: 4.163551ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:31.320246Z","caller":"traceutil/trace.go:171","msg":"trace[333790617] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"215.720729ms","start":"2026-02-18T14:45:31.104497Z","end":"2026-02-18T14:45:31.320217Z","steps":["trace[333790617] 'process raft request' (duration: 214.13679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:31.321877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.547998ms","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-18T14:45:31.322006Z","caller":"traceutil/trace.go:171","msg":"trace[1235201003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"177.745203ms","start":"2026-02-18T14:45:31.144252Z","end":"2026-02-18T14:45:31.321997Z","steps":["trace[1235201003] 'agreement among raft nodes before linearized reading' (duration: 176.984755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:45:31.322059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.150285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:45:31.322113Z","caller":"traceutil/trace.go:171","msg":"trace[1764719147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"188.275107ms","start":"2026-02-18T14:45:31.133827Z","end":"2026-02-18T14:45:31.322103Z","steps":["trace[1764719147] 'agreement among raft nodes before linearized reading' (duration: 187.421157ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:31.322274Z","caller":"traceutil/trace.go:171","msg":"trace[1403026768] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"170.135199ms","start":"2026-02-18T14:45:31.152128Z","end":"2026-02-18T14:45:31.322263Z","steps":["trace[1403026768] 'process raft request' (duration: 168.014209ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:33.690977Z","caller":"traceutil/trace.go:171","msg":"trace[360559347] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"286.225268ms","start":"2026-02-18T14:45:33.404726Z","end":"2026-02-18T14:45:33.690951Z","steps":["trace[360559347] 'process raft request' (duration: 232.501673ms)","trace[360559347] 'compare' (duration: 53.620162ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:45:36.387005Z","caller":"traceutil/trace.go:171","msg":"trace[184265022] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"158.543291ms","start":"2026-02-18T14:45:36.228443Z","end":"2026-02-18T14:45:36.386987Z","steps":["trace[184265022] 'process raft request' (duration: 158.420288ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:45:36.398794Z","caller":"traceutil/trace.go:171","msg":"trace[617000564] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"166.37792ms","start":"2026-02-18T14:45:36.232388Z","end":"2026-02-18T14:45:36.398765Z","steps":["trace[617000564] 'process raft request' (duration: 166.152195ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:00.05859Z","caller":"traceutil/trace.go:171","msg":"trace[1402445705] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"151.303642ms","start":"2026-02-18T14:46:59.907266Z","end":"2026-02-18T14:47:00.05857Z","steps":["trace[1402445705] 'read index received' (duration: 151.137018ms)","trace[1402445705] 'applied index is now lower than readState.Index' (duration: 165.764µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:00.058883Z","caller":"traceutil/trace.go:171","msg":"trace[1407637231] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"414.725806ms","start":"2026-02-18T14:46:59.644142Z","end":"2026-02-18T14:47:00.058868Z","steps":["trace[1407637231] 'process raft request' (duration: 414.310205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:00.059001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:46:59.644121Z","time spent":"414.795267ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:47:00.059273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.002229ms","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-18T14:47:00.059309Z","caller":"traceutil/trace.go:171","msg":"trace[361433674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1372; }","duration":"152.070461ms","start":"2026-02-18T14:46:59.907227Z","end":"2026-02-18T14:47:00.059298Z","steps":["trace[361433674] 'agreement among raft nodes before linearized reading' (duration: 151.937868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:00.254057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.23633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:47:00.254789Z","caller":"traceutil/trace.go:171","msg":"trace[171329670] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"188.72825ms","start":"2026-02-18T14:47:00.066041Z","end":"2026-02-18T14:47:00.254769Z","steps":["trace[171329670] 'process raft request' (duration: 78.078245ms)","trace[171329670] 'compare' (duration: 109.751114ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:00.255253Z","caller":"traceutil/trace.go:171","msg":"trace[790531457] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"189.026509ms","start":"2026-02-18T14:47:00.066215Z","end":"2026-02-18T14:47:00.255241Z","steps":["trace[790531457] 'process raft request' (duration: 187.934282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:00.425542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.485435ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T14:47:00.425847Z","caller":"traceutil/trace.go:171","msg":"trace[121325324] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1425; }","duration":"235.851681ms","start":"2026-02-18T14:47:00.189799Z","end":"2026-02-18T14:47:00.42565Z","steps":["trace[121325324] 'read index received' (duration: 14.838797ms)","trace[121325324] 'applied index is now lower than readState.Index' (duration: 221.001724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:47:00.426141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.325633ms","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-18T14:47:00.426224Z","caller":"traceutil/trace.go:171","msg":"trace[316467789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1374; }","duration":"236.428686ms","start":"2026-02-18T14:47:00.189744Z","end":"2026-02-18T14:47:00.426173Z","steps":["trace[316467789] 'agreement among raft nodes before linearized reading' (duration: 236.257632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:00.426902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.443475ms","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-18T14:47:00.426944Z","caller":"traceutil/trace.go:171","msg":"trace[250908345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1374; }","duration":"236.506627ms","start":"2026-02-18T14:47:00.190427Z","end":"2026-02-18T14:47:00.426934Z","steps":["trace[250908345] 'agreement among raft nodes before linearized reading' (duration: 236.381094ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:14.578945Z","caller":"traceutil/trace.go:171","msg":"trace[310471139] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"163.472785ms","start":"2026-02-18T14:47:14.415456Z","end":"2026-02-18T14:47:14.578929Z","steps":["trace[310471139] 'process raft request' (duration: 162.970683ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:46.546738Z","caller":"traceutil/trace.go:171","msg":"trace[1020584457] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"161.800024ms","start":"2026-02-18T14:47:46.384916Z","end":"2026-02-18T14:47:46.546716Z","steps":["trace[1020584457] 'process raft request' (duration: 161.287572ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:46.992208Z","caller":"traceutil/trace.go:171","msg":"trace[1211401581] linearizableReadLoop","detail":"{readStateIndex:1628; appliedIndex:1626; }","duration":"169.722633ms","start":"2026-02-18T14:47:46.822465Z","end":"2026-02-18T14:47:46.992188Z","steps":["trace[1211401581] 'read index received' (duration: 26.818623ms)","trace[1211401581] 'applied index is now lower than readState.Index' (duration: 142.90311ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:46.992675Z","caller":"traceutil/trace.go:171","msg":"trace[809775439] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"170.255495ms","start":"2026-02-18T14:47:46.822404Z","end":"2026-02-18T14:47:46.992659Z","steps":["trace[809775439] 'process raft request' (duration: 112.708025ms)","trace[809775439] 'compare' (duration: 56.946676ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:47:46.993091Z","caller":"traceutil/trace.go:171","msg":"trace[1074618822] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"168.704ms","start":"2026-02-18T14:47:46.824372Z","end":"2026-02-18T14:47:46.993076Z","steps":["trace[1074618822] 'process raft request' (duration: 167.778897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:46.993773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.287221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-18T14:47:46.994042Z","caller":"traceutil/trace.go:171","msg":"trace[1796122937] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1566; }","duration":"171.578899ms","start":"2026-02-18T14:47:46.822445Z","end":"2026-02-18T14:47:46.994024Z","steps":["trace[1796122937] 'agreement among raft nodes before linearized reading' (duration: 171.2272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:46.994341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.117721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:47:46.994455Z","caller":"traceutil/trace.go:171","msg":"trace[1454779348] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1566; }","duration":"162.259024ms","start":"2026-02-18T14:47:46.832185Z","end":"2026-02-18T14:47:46.994444Z","steps":["trace[1454779348] 'agreement among raft nodes before linearized reading' (duration: 162.112421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:47:47.392721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.717021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:47:47.392789Z","caller":"traceutil/trace.go:171","msg":"trace[1373182332] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"391.444307ms","start":"2026-02-18T14:47:47.001335Z","end":"2026-02-18T14:47:47.39278Z","steps":["trace[1373182332] 'process raft request' (duration: 194.613003ms)","trace[1373182332] 'compare' (duration: 196.557048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:47:47.392828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.22635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T14:47:47.392833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:47:47.001313Z","time spent":"391.498238ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:47:47.392845Z","caller":"traceutil/trace.go:171","msg":"trace[1798674355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1567; }","duration":"261.274492ms","start":"2026-02-18T14:47:47.131565Z","end":"2026-02-18T14:47:47.39284Z","steps":["trace[1798674355] 'agreement among raft nodes before linearized reading' (duration: 261.240861ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:47:47.39279Z","caller":"traceutil/trace.go:171","msg":"trace[877643310] linearizableReadLoop","detail":"{readStateIndex:1630; appliedIndex:1629; }","duration":"261.166759ms","start":"2026-02-18T14:47:47.13161Z","end":"2026-02-18T14:47:47.392777Z","steps":["trace[877643310] 'read index received' (duration: 64.277893ms)","trace[877643310] 'applied index is now lower than readState.Index' (duration: 196.888086ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:05.327287Z","caller":"traceutil/trace.go:171","msg":"trace[520595574] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"125.118133ms","start":"2026-02-18T14:48:05.20215Z","end":"2026-02-18T14:48:05.327268Z","steps":["trace[520595574] 'process raft request' (duration: 78.217851ms)","trace[520595574] 'compare' (duration: 46.826281ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:07.142847Z","caller":"traceutil/trace.go:171","msg":"trace[2005047481] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"106.262323ms","start":"2026-02-18T14:48:07.036553Z","end":"2026-02-18T14:48:07.142815Z","steps":["trace[2005047481] 'process raft request' (duration: 105.908874ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:07.613913Z","caller":"traceutil/trace.go:171","msg":"trace[1796016258] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"136.281911ms","start":"2026-02-18T14:48:07.477604Z","end":"2026-02-18T14:48:07.613886Z","steps":["trace[1796016258] 'process raft request' (duration: 37.370764ms)","trace[1796016258] 'compare' (duration: 98.800014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:27.876376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.73246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:27.876544Z","caller":"traceutil/trace.go:171","msg":"trace[1979914184] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"224.594576ms","start":"2026-02-18T14:48:27.651936Z","end":"2026-02-18T14:48:27.87653Z","steps":["trace[1979914184] 'process raft request' (duration: 103.654221ms)","trace[1979914184] 'compare' (duration: 120.643097ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:29.521256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.796284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:29.521703Z","caller":"traceutil/trace.go:171","msg":"trace[1307551947] transaction","detail":"{read_only:false; response_revision:1739; number_of_response:1; }","duration":"135.483653ms","start":"2026-02-18T14:48:29.386166Z","end":"2026-02-18T14:48:29.52165Z","steps":["trace[1307551947] 'process raft request' (duration: 31.134976ms)","trace[1307551947] 'compare' (duration: 103.63906ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:29.755448Z","caller":"traceutil/trace.go:171","msg":"trace[430607485] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"105.100544ms","start":"2026-02-18T14:48:29.650324Z","end":"2026-02-18T14:48:29.755425Z","steps":["trace[430607485] 'process raft request' (duration: 104.971211ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:29.944831Z","caller":"traceutil/trace.go:171","msg":"trace[1097723417] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"107.036181ms","start":"2026-02-18T14:48:29.837776Z","end":"2026-02-18T14:48:29.944812Z","steps":["trace[1097723417] 'process raft request' (duration: 24.508946ms)","trace[1097723417] 'compare' (duration: 82.356651ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:30.316704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.139677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:30.316927Z","caller":"traceutil/trace.go:171","msg":"trace[1116884463] transaction","detail":"{read_only:false; response_revision:1746; number_of_response:1; }","duration":"138.850714ms","start":"2026-02-18T14:48:30.178057Z","end":"2026-02-18T14:48:30.316907Z","steps":["trace[1116884463] 'process raft request' (duration: 28.40155ms)","trace[1116884463] 'compare' (duration: 109.405998ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:30.505793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.88868ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T14:48:30.784304Z","caller":"traceutil/trace.go:171","msg":"trace[1066183969] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"147.247644ms","start":"2026-02-18T14:48:30.63704Z","end":"2026-02-18T14:48:30.784288Z","steps":["trace[1066183969] 'process raft request' (duration: 147.151972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:31.003547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.934596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:48:31.005578Z","caller":"traceutil/trace.go:171","msg":"trace[651769942] linearizableReadLoop","detail":"{readStateIndex:1822; appliedIndex:1821; }","duration":"138.152487ms","start":"2026-02-18T14:48:30.8674Z","end":"2026-02-18T14:48:31.005552Z","steps":["trace[651769942] 'read index received' (duration: 57.401µs)","trace[651769942] 'applied index is now lower than readState.Index' (duration: 138.093446ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:31.00571Z","caller":"traceutil/trace.go:171","msg":"trace[1211103929] transaction","detail":"{read_only:false; response_revision:1749; number_of_response:1; }","duration":"306.568677ms","start":"2026-02-18T14:48:30.699131Z","end":"2026-02-18T14:48:31.0057Z","steps":["trace[1211103929] 'process raft request' (duration: 147.298275ms)","trace[1211103929] 'compare' (duration: 156.809263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:31.00613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:30.69911Z","time spent":"306.946207ms","remote":"127.0.0.1:41134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:48:31.006253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.858344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-18T14:48:31.006726Z","caller":"traceutil/trace.go:171","msg":"trace[1331251898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1749; }","duration":"139.362205ms","start":"2026-02-18T14:48:30.867355Z","end":"2026-02-18T14:48:31.006717Z","steps":["trace[1331251898] 'agreement among raft nodes before linearized reading' (duration: 138.461724ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:33.206143Z","caller":"traceutil/trace.go:171","msg":"trace[896844065] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"119.240973ms","start":"2026-02-18T14:48:33.086866Z","end":"2026-02-18T14:48:33.206107Z","steps":["trace[896844065] 'process raft request' (duration: 79.930262ms)","trace[896844065] 'compare' (duration: 38.758218ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:48:33.577652Z","caller":"traceutil/trace.go:171","msg":"trace[143723854] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"140.164625ms","start":"2026-02-18T14:48:33.437474Z","end":"2026-02-18T14:48:33.577639Z","steps":["trace[143723854] 'process raft request' (duration: 139.623202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:33.578355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.38149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:48:33.57844Z","caller":"traceutil/trace.go:171","msg":"trace[470795127] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1767; }","duration":"117.516842ms","start":"2026-02-18T14:48:33.460909Z","end":"2026-02-18T14:48:33.578426Z","steps":["trace[470795127] 'agreement among raft nodes before linearized reading' (duration: 117.389679ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:33.578256Z","caller":"traceutil/trace.go:171","msg":"trace[1565856206] linearizableReadLoop","detail":"{readStateIndex:1840; appliedIndex:1839; }","duration":"116.477839ms","start":"2026-02-18T14:48:33.460951Z","end":"2026-02-18T14:48:33.577429Z","steps":["trace[1565856206] 'read index received' (duration: 116.191692ms)","trace[1565856206] 'applied index is now lower than readState.Index' (duration: 285.327µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:33.662668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.453155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-18T14:48:33.663044Z","caller":"traceutil/trace.go:171","msg":"trace[1709315063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1768; }","duration":"118.861374ms","start":"2026-02-18T14:48:33.544138Z","end":"2026-02-18T14:48:33.662999Z","steps":["trace[1709315063] 'agreement among raft nodes before linearized reading' (duration: 118.376513ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:33.663651Z","caller":"traceutil/trace.go:171","msg":"trace[222282887] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"201.82084ms","start":"2026-02-18T14:48:33.461813Z","end":"2026-02-18T14:48:33.663634Z","steps":["trace[222282887] 'process raft request' (duration: 185.161751ms)","trace[222282887] 'compare' (duration: 15.44309ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:48:34.003091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.700861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-18T14:48:34.003448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.917778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:48:34.003839Z","caller":"traceutil/trace.go:171","msg":"trace[1205535029] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1770; }","duration":"155.334498ms","start":"2026-02-18T14:48:33.848484Z","end":"2026-02-18T14:48:34.003818Z","steps":["trace[1205535029] 'count revisions from in-memory index tree' (duration: 154.864117ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:48:34.003164Z","caller":"traceutil/trace.go:171","msg":"trace[1042020436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1770; }","duration":"230.818014ms","start":"2026-02-18T14:48:33.772328Z","end":"2026-02-18T14:48:34.003146Z","steps":["trace[1042020436] 'range keys from in-memory index tree' (duration: 230.476296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:46.196198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.128088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-18T14:48:46.196423Z","caller":"traceutil/trace.go:171","msg":"trace[1236342993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1826; }","duration":"370.388244ms","start":"2026-02-18T14:48:45.826019Z","end":"2026-02-18T14:48:46.196407Z","steps":["trace[1236342993] 'range keys from in-memory index tree' (duration: 369.963355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:46.19661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:48:45.825996Z","time spent":"370.549028ms","remote":"127.0.0.1:41212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T14:48:46.195648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.657988ms","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-18T14:48:46.19792Z","caller":"traceutil/trace.go:171","msg":"trace[232367612] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1826; }","duration":"389.940952ms","start":"2026-02-18T14:48:45.807965Z","end":"2026-02-18T14:48:46.197906Z","steps":["trace[232367612] 'range keys from in-memory index tree' (duration: 387.648387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:46.472219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.655708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T14:48:46.472483Z","caller":"traceutil/trace.go:171","msg":"trace[1591634938] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1828; }","duration":"153.873223ms","start":"2026-02-18T14:48:46.318494Z","end":"2026-02-18T14:48:46.472368Z","steps":["trace[1591634938] 'range keys from in-memory index tree' (duration: 153.498104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:57.251526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.545443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:48:57.251604Z","caller":"traceutil/trace.go:171","msg":"trace[799783894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1880; }","duration":"117.687666ms","start":"2026-02-18T14:48:57.133899Z","end":"2026-02-18T14:48:57.251587Z","steps":["trace[799783894] 'range keys from in-memory index tree' (duration: 117.47039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:48:57.251957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.172756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:48:57.25278Z","caller":"traceutil/trace.go:171","msg":"trace[928822255] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1880; }","duration":"116.064006ms","start":"2026-02-18T14:48:57.136694Z","end":"2026-02-18T14:48:57.252758Z","steps":["trace[928822255] 'range keys from in-memory index tree' (duration: 115.116984ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:05.473808Z","caller":"traceutil/trace.go:171","msg":"trace[612141151] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1999; }","duration":"169.878706ms","start":"2026-02-18T14:49:05.303908Z","end":"2026-02-18T14:49:05.473786Z","steps":["trace[612141151] 'read index received' (duration: 169.718972ms)","trace[612141151] 'applied index is now lower than readState.Index' (duration: 158.914µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:05.474097Z","caller":"traceutil/trace.go:171","msg":"trace[193420148] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"187.126385ms","start":"2026-02-18T14:49:05.286959Z","end":"2026-02-18T14:49:05.474085Z","steps":["trace[193420148] 'process raft request' (duration: 186.722616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:05.474336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.409989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-18T14:49:05.474368Z","caller":"traceutil/trace.go:171","msg":"trace[252397649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1919; }","duration":"170.47328ms","start":"2026-02-18T14:49:05.303884Z","end":"2026-02-18T14:49:05.474358Z","steps":["trace[252397649] 'agreement among raft nodes before linearized reading' (duration: 170.363827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:05.474523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.554927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-02-18T14:49:05.474549Z","caller":"traceutil/trace.go:171","msg":"trace[1121571830] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1919; }","duration":"158.608178ms","start":"2026-02-18T14:49:05.315933Z","end":"2026-02-18T14:49:05.474541Z","steps":["trace[1121571830] 'agreement among raft nodes before linearized reading' (duration: 158.558397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:05.47492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.547795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T14:49:05.474949Z","caller":"traceutil/trace.go:171","msg":"trace[2054991461] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1919; }","duration":"115.576805ms","start":"2026-02-18T14:49:05.359364Z","end":"2026-02-18T14:49:05.47494Z","steps":["trace[2054991461] 'agreement among raft nodes before linearized reading' (duration: 115.526944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:05.680499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.002333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:49:05.680676Z","caller":"traceutil/trace.go:171","msg":"trace[765790465] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:2000; }","duration":"127.033818ms","start":"2026-02-18T14:49:05.553627Z","end":"2026-02-18T14:49:05.68066Z","steps":["trace[765790465] 'read index received' (duration: 8.862841ms)","trace[765790465] 'applied index is now lower than readState.Index' (duration: 118.170047ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:05.680736Z","caller":"traceutil/trace.go:171","msg":"trace[464720024] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"190.909475ms","start":"2026-02-18T14:49:05.489817Z","end":"2026-02-18T14:49:05.680726Z","steps":["trace[464720024] 'process raft request' (duration: 72.603485ms)","trace[464720024] 'compare' (duration: 117.696236ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:05.680827Z","caller":"traceutil/trace.go:171","msg":"trace[1793520997] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"189.075261ms","start":"2026-02-18T14:49:05.491723Z","end":"2026-02-18T14:49:05.680799Z","steps":["trace[1793520997] 'process raft request' (duration: 188.861416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:05.680928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.295625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T14:49:05.680958Z","caller":"traceutil/trace.go:171","msg":"trace[1880056069] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1921; }","duration":"127.349225ms","start":"2026-02-18T14:49:05.5536Z","end":"2026-02-18T14:49:05.680949Z","steps":["trace[1880056069] 'agreement among raft nodes before linearized reading' (duration: 127.286814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:06.016501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.875791ms","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-18T14:49:06.016641Z","caller":"traceutil/trace.go:171","msg":"trace[170536006] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1921; }","duration":"209.027775ms","start":"2026-02-18T14:49:05.807598Z","end":"2026-02-18T14:49:06.016626Z","steps":["trace[170536006] 'range keys from in-memory index tree' (duration: 208.83049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:06.017804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.648355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:49:06.018236Z","caller":"traceutil/trace.go:171","msg":"trace[441420692] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"452.408588ms","start":"2026-02-18T14:49:05.565808Z","end":"2026-02-18T14:49:06.018217Z","steps":["trace[441420692] 'process raft request' (duration: 317.984785ms)","trace[441420692] 'compare' (duration: 132.525598ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:06.018519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:49:05.565791Z","time spent":"452.49903ms","remote":"127.0.0.1:41122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6077,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:49:06.01915Z","caller":"traceutil/trace.go:171","msg":"trace[1510317950] linearizableReadLoop","detail":"{readStateIndex:2003; appliedIndex:2002; }","duration":"337.289923ms","start":"2026-02-18T14:49:05.680703Z","end":"2026-02-18T14:49:06.017993Z","steps":["trace[1510317950] 'read index received' (duration: 203.030954ms)","trace[1510317950] 'applied index is now lower than readState.Index' (duration: 134.257499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:06.019248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.794773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-02-18T14:49:06.020638Z","caller":"traceutil/trace.go:171","msg":"trace[936635351] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1922; }","duration":"447.097581ms","start":"2026-02-18T14:49:05.572423Z","end":"2026-02-18T14:49:06.019521Z","steps":["trace[936635351] 'agreement among raft nodes before linearized reading' (duration: 446.770773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:06.020699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:49:05.572414Z","time spent":"448.269989ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":564,"request content":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" "} {"level":"warn","ts":"2026-02-18T14:49:06.025002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.210832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-18T14:49:06.027248Z","caller":"traceutil/trace.go:171","msg":"trace[1928878103] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:1922; }","duration":"342.465445ms","start":"2026-02-18T14:49:05.684756Z","end":"2026-02-18T14:49:06.027221Z","steps":["trace[1928878103] 'agreement among raft nodes before linearized reading' (duration: 340.066948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:06.02741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:49:05.684745Z","time spent":"342.633518ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":458,"request content":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" "} {"level":"info","ts":"2026-02-18T14:49:06.296195Z","caller":"traceutil/trace.go:171","msg":"trace[1242766700] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2004; }","duration":"170.713256ms","start":"2026-02-18T14:49:06.12546Z","end":"2026-02-18T14:49:06.296174Z","steps":["trace[1242766700] 'read index received' (duration: 109.608514ms)","trace[1242766700] 'applied index is now lower than readState.Index' (duration: 61.103782ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:06.296886Z","caller":"traceutil/trace.go:171","msg":"trace[134298703] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1924; }","duration":"240.065162ms","start":"2026-02-18T14:49:06.056806Z","end":"2026-02-18T14:49:06.296871Z","steps":["trace[134298703] 'process raft request' (duration: 178.226933ms)","trace[134298703] 'compare' (duration: 60.731603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:06.297317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.081438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-02-18T14:49:06.296976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.497634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-02-18T14:49:06.298658Z","caller":"traceutil/trace.go:171","msg":"trace[3522998] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:1924; }","duration":"173.211415ms","start":"2026-02-18T14:49:06.125433Z","end":"2026-02-18T14:49:06.298644Z","steps":["trace[3522998] 'agreement among raft nodes before linearized reading' (duration: 171.489743ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:06.298858Z","caller":"traceutil/trace.go:171","msg":"trace[1713711466] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:1924; }","duration":"165.650094ms","start":"2026-02-18T14:49:06.133198Z","end":"2026-02-18T14:49:06.298848Z","steps":["trace[1713711466] 'agreement among raft nodes before linearized reading' (duration: 164.083428ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:06.493759Z","caller":"traceutil/trace.go:171","msg":"trace[1508443728] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"111.767015ms","start":"2026-02-18T14:49:06.38192Z","end":"2026-02-18T14:49:06.493687Z","steps":["trace[1508443728] 'process raft request' (duration: 54.908644ms)","trace[1508443728] 'compare' (duration: 56.729308ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:06.511519Z","caller":"traceutil/trace.go:171","msg":"trace[1220426755] linearizableReadLoop","detail":"{readStateIndex:2007; appliedIndex:2005; }","duration":"122.791947ms","start":"2026-02-18T14:49:06.388707Z","end":"2026-02-18T14:49:06.511499Z","steps":["trace[1220426755] 'read index received' (duration: 48.132383ms)","trace[1220426755] 'applied index is now lower than readState.Index' (duration: 74.658704ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:06.511668Z","caller":"traceutil/trace.go:171","msg":"trace[1903681491] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"126.428223ms","start":"2026-02-18T14:49:06.38523Z","end":"2026-02-18T14:49:06.511658Z","steps":["trace[1903681491] 'process raft request' (duration: 125.921891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:06.51207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.34768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-02-18T14:49:06.51216Z","caller":"traceutil/trace.go:171","msg":"trace[1339243419] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:1; response_revision:1926; }","duration":"123.458312ms","start":"2026-02-18T14:49:06.388688Z","end":"2026-02-18T14:49:06.512146Z","steps":["trace[1339243419] 'agreement among raft nodes before linearized reading' (duration: 123.325469ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:07.803223Z","caller":"traceutil/trace.go:171","msg":"trace[1024205715] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"113.141037ms","start":"2026-02-18T14:49:07.690068Z","end":"2026-02-18T14:49:07.803209Z","steps":["trace[1024205715] 'process raft request' (duration: 113.055695ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:07.807468Z","caller":"traceutil/trace.go:171","msg":"trace[86396390] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"117.269106ms","start":"2026-02-18T14:49:07.690189Z","end":"2026-02-18T14:49:07.807458Z","steps":["trace[86396390] 'process raft request' (duration: 117.140713ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:08.165505Z","caller":"traceutil/trace.go:171","msg":"trace[135694328] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"104.957073ms","start":"2026-02-18T14:49:08.060526Z","end":"2026-02-18T14:49:08.165483Z","steps":["trace[135694328] 'process raft request' (duration: 65.401244ms)","trace[135694328] 'compare' (duration: 39.285883ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:09.029862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.202217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:expand-controller\" ","response":"range_response_count:1 size:882"} {"level":"info","ts":"2026-02-18T14:49:09.029931Z","caller":"traceutil/trace.go:171","msg":"trace[194733849] range","detail":"{range_begin:/registry/clusterroles/system:controller:expand-controller; range_end:; response_count:1; response_revision:1936; }","duration":"107.307639ms","start":"2026-02-18T14:49:08.922607Z","end":"2026-02-18T14:49:09.029915Z","steps":["trace[194733849] 'range keys from in-memory index tree' (duration: 107.106035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:09.03016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.326193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-02-18T14:49:09.030188Z","caller":"traceutil/trace.go:171","msg":"trace[1514263924] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1936; }","duration":"100.385335ms","start":"2026-02-18T14:49:08.929794Z","end":"2026-02-18T14:49:09.030179Z","steps":["trace[1514263924] 'range keys from in-memory index tree' (duration: 100.257702ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:09.468357Z","caller":"traceutil/trace.go:171","msg":"trace[871099947] linearizableReadLoop","detail":"{readStateIndex:2018; appliedIndex:2017; }","duration":"108.322634ms","start":"2026-02-18T14:49:09.360012Z","end":"2026-02-18T14:49:09.468334Z","steps":["trace[871099947] 'read index received' (duration: 108.167619ms)","trace[871099947] 'applied index is now lower than readState.Index' (duration: 154.025µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:09.468576Z","caller":"traceutil/trace.go:171","msg":"trace[1779774712] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"134.952107ms","start":"2026-02-18T14:49:09.333585Z","end":"2026-02-18T14:49:09.468537Z","steps":["trace[1779774712] 'process raft request' (duration: 134.635478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:09.4688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.766244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-02-18T14:49:09.468876Z","caller":"traceutil/trace.go:171","msg":"trace[1204598371] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1937; }","duration":"108.871966ms","start":"2026-02-18T14:49:09.359985Z","end":"2026-02-18T14:49:09.468857Z","steps":["trace[1204598371] 'agreement among raft nodes before linearized reading' (duration: 108.760224ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:16.777761Z","caller":"traceutil/trace.go:171","msg":"trace[2052418520] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"121.381664ms","start":"2026-02-18T14:49:16.65636Z","end":"2026-02-18T14:49:16.777742Z","steps":["trace[2052418520] 'process raft request' (duration: 121.099808ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:16.889908Z","caller":"traceutil/trace.go:171","msg":"trace[158271304] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"107.376822ms","start":"2026-02-18T14:49:16.782508Z","end":"2026-02-18T14:49:16.889885Z","steps":["trace[158271304] 'process raft request' (duration: 58.451099ms)","trace[158271304] 'compare' (duration: 48.837601ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:23.381136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.351147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:12 size:88677"} {"level":"info","ts":"2026-02-18T14:49:23.381337Z","caller":"traceutil/trace.go:171","msg":"trace[405899310] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:12; response_revision:1992; }","duration":"157.587693ms","start":"2026-02-18T14:49:23.223732Z","end":"2026-02-18T14:49:23.38132Z","steps":["trace[405899310] 'range keys from in-memory index tree' (duration: 157.170913ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:34.944551Z","caller":"traceutil/trace.go:171","msg":"trace[109131619] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2126; }","duration":"124.435446ms","start":"2026-02-18T14:49:34.820094Z","end":"2026-02-18T14:49:34.94453Z","steps":["trace[109131619] 'read index received' (duration: 114.723235ms)","trace[109131619] 'applied index is now lower than readState.Index' (duration: 9.711261ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:34.944746Z","caller":"traceutil/trace.go:171","msg":"trace[398030514] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"128.410331ms","start":"2026-02-18T14:49:34.816322Z","end":"2026-02-18T14:49:34.944732Z","steps":["trace[398030514] 'process raft request' (duration: 118.529235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:49:34.945006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.858467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:49:34.945487Z","caller":"traceutil/trace.go:171","msg":"trace[868740405] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:2040; }","duration":"125.275296ms","start":"2026-02-18T14:49:34.820071Z","end":"2026-02-18T14:49:34.945347Z","steps":["trace[868740405] 'agreement among raft nodes before linearized reading' (duration: 124.709103ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:35.145475Z","caller":"traceutil/trace.go:171","msg":"trace[2119084395] linearizableReadLoop","detail":"{readStateIndex:2130; appliedIndex:2129; }","duration":"117.44359ms","start":"2026-02-18T14:49:35.028006Z","end":"2026-02-18T14:49:35.145449Z","steps":["trace[2119084395] 'read index received' (duration: 22.606118ms)","trace[2119084395] 'applied index is now lower than readState.Index' (duration: 94.836622ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:49:35.145712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.649745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:49:35.145824Z","caller":"traceutil/trace.go:171","msg":"trace[1457388616] range","detail":"{range_begin:/registry/pods/kube-node-lease/; range_end:/registry/pods/kube-node-lease0; response_count:0; response_revision:2043; }","duration":"117.746928ms","start":"2026-02-18T14:49:35.027996Z","end":"2026-02-18T14:49:35.145743Z","steps":["trace[1457388616] 'agreement among raft nodes before linearized reading' (duration: 117.540852ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:49:35.146176Z","caller":"traceutil/trace.go:171","msg":"trace[906585945] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"120.850401ms","start":"2026-02-18T14:49:35.025291Z","end":"2026-02-18T14:49:35.146141Z","steps":["trace[906585945] 'process raft request' (duration: 25.303962ms)","trace[906585945] 'compare' (duration: 94.740089ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:49:36.316731Z","caller":"traceutil/trace.go:171","msg":"trace[75990280] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"101.791839ms","start":"2026-02-18T14:49:36.214915Z","end":"2026-02-18T14:49:36.316706Z","steps":["trace[75990280] 'process raft request' (duration: 69.086732ms)","trace[75990280] 'compare' (duration: 32.272476ms)"],"step_count":2}