{"level":"warn","ts":"2026-02-18T22:48:27.355108Z","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-18T22:48:27.355169Z","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-18T22:48:27.355225Z","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-18T22:48:27.355233Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-18T22:48:27.355249Z","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-18T22:48:27.355767Z","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-18T22:48:27.355828Z","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-18T22:48:27.359048Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.036739ms"} {"level":"info","ts":"2026-02-18T22:48:27.397578Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-18T22:48:27.397676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:48:27.397698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-18T22:48:27.397706Z","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-18T22:48:27.397714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-18T22:48:27.397749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-18T22:48:27.413625Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:48:27.418489Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:48:27.429596Z","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-18T22:48:27.435325Z","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-18T22:48:27.436543Z","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-18T22:48:27.437224Z","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-18T22:48:27.437463Z","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-18T22:48:27.437559Z","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-18T22:48:27.439375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-18T22:48:27.440066Z","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-18T22:48:27.474701Z","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-18T22:48:27.475018Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-18T22:48:27.47503Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-18T22:48:27.475154Z","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-18T22:48:27.475169Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:48:28.302409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:48:28.302461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:48:28.302477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-18T22:48:28.302488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:48:28.302493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-18T22:48:28.302501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-18T22:48:28.302508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-18T22:48:28.312797Z","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-18T22:48:28.312894Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:48:28.31313Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:48:28.313192Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:48:28.313299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:48:28.313308Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T22:48:28.31475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-18T22:48:28.314803Z","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-18T22:48:28.314838Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:48:28.31485Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:48:28.329192Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T22:48:33.224123Z","caller":"traceutil/trace.go:171","msg":"trace[59622692] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:276; }","duration":"101.517058ms","start":"2026-02-18T22:48:33.122588Z","end":"2026-02-18T22:48:33.224105Z","steps":["trace[59622692] 'read index received' (duration: 27.866872ms)","trace[59622692] 'applied index is now lower than readState.Index' (duration: 73.648846ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:33.224237Z","caller":"traceutil/trace.go:171","msg":"trace[620729053] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"110.927747ms","start":"2026-02-18T22:48:33.113297Z","end":"2026-02-18T22:48:33.224225Z","steps":["trace[620729053] 'process raft request' (duration: 37.14769ms)","trace[620729053] 'compare' (duration: 72.73891ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:33.224426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.071882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-18T22:48:33.224469Z","caller":"traceutil/trace.go:171","msg":"trace[1541442296] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:272; }","duration":"110.152613ms","start":"2026-02-18T22:48:33.114305Z","end":"2026-02-18T22:48:33.224457Z","steps":["trace[1541442296] 'agreement among raft nodes before linearized reading' (duration: 110.039782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:33.23156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.165993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3705"} {"level":"info","ts":"2026-02-18T22:48:33.231594Z","caller":"traceutil/trace.go:171","msg":"trace[1026010191] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:275; }","duration":"107.217923ms","start":"2026-02-18T22:48:33.124368Z","end":"2026-02-18T22:48:33.231586Z","steps":["trace[1026010191] 'agreement among raft nodes before linearized reading' (duration: 107.168293ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:35.32269Z","caller":"traceutil/trace.go:171","msg":"trace[876616431] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"148.007137ms","start":"2026-02-18T22:48:35.174674Z","end":"2026-02-18T22:48:35.322681Z","steps":["trace[876616431] 'process raft request' (duration: 147.695454ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:35.322478Z","caller":"traceutil/trace.go:171","msg":"trace[1978718922] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:329; }","duration":"117.880223ms","start":"2026-02-18T22:48:35.204583Z","end":"2026-02-18T22:48:35.322464Z","steps":["trace[1978718922] 'read index received' (duration: 117.728481ms)","trace[1978718922] 'applied index is now lower than readState.Index' (duration: 151.232µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:35.322938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.329645ms","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-18T22:48:35.32296Z","caller":"traceutil/trace.go:171","msg":"trace[368010982] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:323; }","duration":"118.399676ms","start":"2026-02-18T22:48:35.204551Z","end":"2026-02-18T22:48:35.322951Z","steps":["trace[368010982] 'agreement among raft nodes before linearized reading' (duration: 118.338185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:35.762005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.260436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T22:48:35.762068Z","caller":"traceutil/trace.go:171","msg":"trace[855077378] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"151.352327ms","start":"2026-02-18T22:48:35.610699Z","end":"2026-02-18T22:48:35.762052Z","steps":["trace[855077378] 'range keys from in-memory index tree' (duration: 151.189406ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:35.762097Z","caller":"traceutil/trace.go:171","msg":"trace[140646726] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"150.27826ms","start":"2026-02-18T22:48:35.611792Z","end":"2026-02-18T22:48:35.76207Z","steps":["trace[140646726] 'process raft request' (duration: 86.610321ms)","trace[140646726] 'compare' (duration: 63.514298ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:36.124117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.817538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:36.124256Z","caller":"traceutil/trace.go:171","msg":"trace[1934274026] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"266.401169ms","start":"2026-02-18T22:48:35.857833Z","end":"2026-02-18T22:48:36.124234Z","steps":["trace[1934274026] 'process raft request' (duration: 37.027017ms)","trace[1934274026] 'compare' (duration: 228.715887ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:36.12419Z","caller":"traceutil/trace.go:171","msg":"trace[2075824614] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"219.789673ms","start":"2026-02-18T22:48:35.90439Z","end":"2026-02-18T22:48:36.12418Z","steps":["trace[2075824614] 'read index received' (duration: 31.48µs)","trace[2075824614] 'applied index is now lower than readState.Index' (duration: 219.757483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:36.124734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.340636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:36.124759Z","caller":"traceutil/trace.go:171","msg":"trace[150787743] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:330; }","duration":"220.404867ms","start":"2026-02-18T22:48:35.904347Z","end":"2026-02-18T22:48:36.124752Z","steps":["trace[150787743] 'agreement among raft nodes before linearized reading' (duration: 220.360066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:36.399877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.785851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:36.400135Z","caller":"traceutil/trace.go:171","msg":"trace[529919991] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"271.063692ms","start":"2026-02-18T22:48:36.129048Z","end":"2026-02-18T22:48:36.400112Z","steps":["trace[529919991] 'process raft request' (duration: 112.969389ms)","trace[529919991] 'compare' (duration: 157.49424ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:36.402126Z","caller":"traceutil/trace.go:171","msg":"trace[1953154427] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:337; }","duration":"106.380999ms","start":"2026-02-18T22:48:36.295731Z","end":"2026-02-18T22:48:36.402112Z","steps":["trace[1953154427] 'read index received' (duration: 1.988023ms)","trace[1953154427] 'applied index is now lower than readState.Index' (duration: 104.392196ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:36.402824Z","caller":"traceutil/trace.go:171","msg":"trace[778134973] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"273.104575ms","start":"2026-02-18T22:48:36.129702Z","end":"2026-02-18T22:48:36.402806Z","steps":["trace[778134973] 'process raft request' (duration: 270.280468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:36.40248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.732191ms","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-18T22:48:36.403232Z","caller":"traceutil/trace.go:171","msg":"trace[2129311443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:333; }","duration":"107.543245ms","start":"2026-02-18T22:48:36.295675Z","end":"2026-02-18T22:48:36.403218Z","steps":["trace[2129311443] 'agreement among raft nodes before linearized reading' (duration: 106.495049ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:36.40413Z","caller":"traceutil/trace.go:171","msg":"trace[1308944960] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"272.526322ms","start":"2026-02-18T22:48:36.131575Z","end":"2026-02-18T22:48:36.404101Z","steps":["trace[1308944960] 'process raft request' (duration: 270.48511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:36.879206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.541041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:36.879282Z","caller":"traceutil/trace.go:171","msg":"trace[1574476812] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"398.295118ms","start":"2026-02-18T22:48:36.480974Z","end":"2026-02-18T22:48:36.879269Z","steps":["trace[1574476812] 'process raft request' (duration: 71.644557ms)","trace[1574476812] 'compare' (duration: 326.41499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:36.879318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:36.480958Z","time spent":"398.347578ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:48:37.654224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.820294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:37.654404Z","caller":"traceutil/trace.go:171","msg":"trace[790438268] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"754.914114ms","start":"2026-02-18T22:48:36.899469Z","end":"2026-02-18T22:48:37.654383Z","steps":["trace[790438268] 'process raft request' (duration: 122.858399ms)","trace[790438268] 'compare' (duration: 631.707003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:37.654578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:36.89946Z","time spent":"755.090565ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:48:37.903044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.825724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:37.903759Z","caller":"traceutil/trace.go:171","msg":"trace[1716002444] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"104.640114ms","start":"2026-02-18T22:48:37.799102Z","end":"2026-02-18T22:48:37.903743Z","steps":["trace[1716002444] 'read index received' (duration: 1.030326ms)","trace[1716002444] 'applied index is now lower than readState.Index' (duration: 103.608288ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:37.903785Z","caller":"traceutil/trace.go:171","msg":"trace[1612514813] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"240.846332ms","start":"2026-02-18T22:48:37.662915Z","end":"2026-02-18T22:48:37.903761Z","steps":["trace[1612514813] 'process raft request' (duration: 137.258853ms)","trace[1612514813] 'compare' (duration: 102.736314ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:37.903991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.852896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-18T22:48:37.904072Z","caller":"traceutil/trace.go:171","msg":"trace[248852946] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:338; }","duration":"104.997727ms","start":"2026-02-18T22:48:37.799064Z","end":"2026-02-18T22:48:37.904062Z","steps":["trace[248852946] 'agreement among raft nodes before linearized reading' (duration: 104.767426ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:38.224448Z","caller":"traceutil/trace.go:171","msg":"trace[974051166] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"311.145352ms","start":"2026-02-18T22:48:37.913282Z","end":"2026-02-18T22:48:38.224427Z","steps":["trace[974051166] 'read index received' (duration: 252.65059ms)","trace[974051166] 'applied index is now lower than readState.Index' (duration: 58.493852ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:38.224661Z","caller":"traceutil/trace.go:171","msg":"trace[46812487] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"312.300431ms","start":"2026-02-18T22:48:37.912352Z","end":"2026-02-18T22:48:38.224652Z","steps":["trace[46812487] 'process raft request' (duration: 253.648656ms)","trace[46812487] 'compare' (duration: 58.355592ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:38.224738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:37.91234Z","time spent":"312.346431ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:38.224871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.586956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T22:48:38.224898Z","caller":"traceutil/trace.go:171","msg":"trace[133765197] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:339; }","duration":"311.637617ms","start":"2026-02-18T22:48:37.913253Z","end":"2026-02-18T22:48:38.224891Z","steps":["trace[133765197] 'agreement among raft nodes before linearized reading' (duration: 311.593697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:38.224919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:37.913239Z","time spent":"311.676157ms","remote":"127.0.0.1:51034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-18T22:48:38.621847Z","caller":"traceutil/trace.go:171","msg":"trace[1409170893] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"391.178512ms","start":"2026-02-18T22:48:38.23065Z","end":"2026-02-18T22:48:38.621829Z","steps":["trace[1409170893] 'process raft request' (duration: 391.099891ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:38.622018Z","caller":"traceutil/trace.go:171","msg":"trace[520524824] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"391.434504ms","start":"2026-02-18T22:48:38.230559Z","end":"2026-02-18T22:48:38.621994Z","steps":["trace[520524824] 'read index received' (duration: 326.034439ms)","trace[520524824] 'applied index is now lower than readState.Index' (duration: 65.398235ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:38.622141Z","caller":"traceutil/trace.go:171","msg":"trace[72680392] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"391.803616ms","start":"2026-02-18T22:48:38.230326Z","end":"2026-02-18T22:48:38.622129Z","steps":["trace[72680392] 'process raft request' (duration: 326.25472ms)","trace[72680392] 'compare' (duration: 65.041833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:38.622223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:38.230309Z","time spent":"391.857616ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:48:38.62272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.148759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-18T22:48:38.622761Z","caller":"traceutil/trace.go:171","msg":"trace[934691385] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:341; }","duration":"392.241189ms","start":"2026-02-18T22:48:38.230509Z","end":"2026-02-18T22:48:38.62275Z","steps":["trace[934691385] 'agreement among raft nodes before linearized reading' (duration: 392.111788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:38.622791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:38.230492Z","time spent":"392.292619ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6921,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-18T22:48:38.622905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.9472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:48:38.622935Z","caller":"traceutil/trace.go:171","msg":"trace[707664606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:341; }","duration":"212.015551ms","start":"2026-02-18T22:48:38.410911Z","end":"2026-02-18T22:48:38.622926Z","steps":["trace[707664606] 'agreement among raft nodes before linearized reading' (duration: 211.962871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:38.622725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:38.230636Z","time spent":"391.270372ms","remote":"127.0.0.1:51172","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-18T22:48:38.623071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.649272ms","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-18T22:48:38.623088Z","caller":"traceutil/trace.go:171","msg":"trace[896236760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:341; }","duration":"190.698292ms","start":"2026-02-18T22:48:38.432385Z","end":"2026-02-18T22:48:38.623083Z","steps":["trace[896236760] 'agreement among raft nodes before linearized reading' (duration: 190.669292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:38.623188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.96858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:48:38.623202Z","caller":"traceutil/trace.go:171","msg":"trace[1177682394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:341; }","duration":"212.002171ms","start":"2026-02-18T22:48:38.411195Z","end":"2026-02-18T22:48:38.623197Z","steps":["trace[1177682394] 'agreement among raft nodes before linearized reading' (duration: 211.94506ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:38.901949Z","caller":"traceutil/trace.go:171","msg":"trace[1313034981] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"191.089014ms","start":"2026-02-18T22:48:38.710836Z","end":"2026-02-18T22:48:38.901925Z","steps":["trace[1313034981] 'process raft request' (duration: 133.285715ms)","trace[1313034981] 'compare' (duration: 57.675298ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:39.147641Z","caller":"traceutil/trace.go:171","msg":"trace[37973256] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"172.378028ms","start":"2026-02-18T22:48:38.975236Z","end":"2026-02-18T22:48:39.147614Z","steps":["trace[37973256] 'process raft request' (duration: 72.90732ms)","trace[37973256] 'compare' (duration: 99.262577ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:39.476269Z","caller":"traceutil/trace.go:171","msg":"trace[347850540] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"270.364155ms","start":"2026-02-18T22:48:39.205885Z","end":"2026-02-18T22:48:39.476249Z","steps":["trace[347850540] 'process raft request' (duration: 216.457112ms)","trace[347850540] 'compare' (duration: 53.752352ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:39.849285Z","caller":"traceutil/trace.go:171","msg":"trace[1537586133] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"324.190919ms","start":"2026-02-18T22:48:39.52507Z","end":"2026-02-18T22:48:39.849261Z","steps":["trace[1537586133] 'process raft request' (duration: 232.223396ms)","trace[1537586133] 'compare' (duration: 91.853992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:39.849543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.52506Z","time spent":"324.292659ms","remote":"127.0.0.1:51394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3620,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:48:40.256075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.44084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:40.256866Z","caller":"traceutil/trace.go:171","msg":"trace[2039847714] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"728.580434ms","start":"2026-02-18T22:48:39.528265Z","end":"2026-02-18T22:48:40.256846Z","steps":["trace[2039847714] 'process raft request' (duration: 437.176029ms)","trace[2039847714] 'compare' (duration: 289.989516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:40.257403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.528259Z","time spent":"729.101297ms","remote":"127.0.0.1:51076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:40.257734Z","caller":"traceutil/trace.go:171","msg":"trace[1163725548] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"400.471302ms","start":"2026-02-18T22:48:39.857237Z","end":"2026-02-18T22:48:40.257708Z","steps":["trace[1163725548] 'process raft request' (duration: 400.381502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.257901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.857229Z","time spent":"400.589113ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:40.258205Z","caller":"traceutil/trace.go:171","msg":"trace[1666589993] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:358; }","duration":"703.483424ms","start":"2026-02-18T22:48:39.554706Z","end":"2026-02-18T22:48:40.258189Z","steps":["trace[1666589993] 'read index received' (duration: 202.542999ms)","trace[1666589993] 'applied index is now lower than readState.Index' (duration: 500.939065ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:40.258293Z","caller":"traceutil/trace.go:171","msg":"trace[466841848] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"401.815219ms","start":"2026-02-18T22:48:39.85647Z","end":"2026-02-18T22:48:40.258285Z","steps":["trace[466841848] 'process raft request' (duration: 400.335881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.258442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.856393Z","time spent":"401.9258ms","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:48:40.258657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.890342ms","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-18T22:48:40.258748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.130412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-18T22:48:40.25878Z","caller":"traceutil/trace.go:171","msg":"trace[460553348] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:355; }","duration":"402.181252ms","start":"2026-02-18T22:48:39.856591Z","end":"2026-02-18T22:48:40.258773Z","steps":["trace[460553348] 'agreement among raft nodes before linearized reading' (duration: 402.128592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.2588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.856585Z","time spent":"402.210122ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:40.258909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.201869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-18T22:48:40.258931Z","caller":"traceutil/trace.go:171","msg":"trace[436324195] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:355; }","duration":"704.248199ms","start":"2026-02-18T22:48:39.554677Z","end":"2026-02-18T22:48:40.258926Z","steps":["trace[436324195] 'agreement among raft nodes before linearized reading' (duration: 704.212359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.258948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.554667Z","time spent":"704.277089ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:40.259027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.098398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-18T22:48:40.259048Z","caller":"traceutil/trace.go:171","msg":"trace[494074120] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:355; }","duration":"115.129389ms","start":"2026-02-18T22:48:40.143913Z","end":"2026-02-18T22:48:40.259043Z","steps":["trace[494074120] 'agreement among raft nodes before linearized reading' (duration: 115.096168ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:40.25913Z","caller":"traceutil/trace.go:171","msg":"trace[1577182814] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:355; }","duration":"700.019983ms","start":"2026-02-18T22:48:39.558741Z","end":"2026-02-18T22:48:40.258761Z","steps":["trace[1577182814] 'agreement among raft nodes before linearized reading' (duration: 699.875102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.259229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.558734Z","time spent":"700.484276ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-18T22:48:40.259372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.486711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-18T22:48:40.259397Z","caller":"traceutil/trace.go:171","msg":"trace[1467764424] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:355; }","duration":"115.522022ms","start":"2026-02-18T22:48:40.143869Z","end":"2026-02-18T22:48:40.259391Z","steps":["trace[1467764424] 'agreement among raft nodes before linearized reading' (duration: 115.455021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.259633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.402518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:40.259719Z","caller":"traceutil/trace.go:171","msg":"trace[1911133096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:355; }","duration":"441.520418ms","start":"2026-02-18T22:48:39.81819Z","end":"2026-02-18T22:48:40.25971Z","steps":["trace[1911133096] 'agreement among raft nodes before linearized reading' (duration: 441.419548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.259814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.818132Z","time spent":"441.67441ms","remote":"127.0.0.1:50902","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-18T22:48:40.25993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.243686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-18T22:48:40.25998Z","caller":"traceutil/trace.go:171","msg":"trace[1115062396] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:355; }","duration":"116.282896ms","start":"2026-02-18T22:48:40.14368Z","end":"2026-02-18T22:48:40.259963Z","steps":["trace[1115062396] 'agreement among raft nodes before linearized reading' (duration: 116.156885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.260133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.789634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-18T22:48:40.260234Z","caller":"traceutil/trace.go:171","msg":"trace[994087100] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:355; }","duration":"648.881614ms","start":"2026-02-18T22:48:39.611313Z","end":"2026-02-18T22:48:40.260195Z","steps":["trace[994087100] 'agreement among raft nodes before linearized reading' (duration: 648.785884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.260361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.611302Z","time spent":"649.047116ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:40.260579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.404338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-18T22:48:40.260674Z","caller":"traceutil/trace.go:171","msg":"trace[2109226414] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:355; }","duration":"649.480599ms","start":"2026-02-18T22:48:39.611147Z","end":"2026-02-18T22:48:40.260628Z","steps":["trace[2109226414] 'agreement among raft nodes before linearized reading' (duration: 649.405888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.260771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.611137Z","time spent":"649.625ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-18T22:48:40.262159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.544184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-18T22:48:40.262304Z","caller":"traceutil/trace.go:171","msg":"trace[1215976108] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:355; }","duration":"668.702055ms","start":"2026-02-18T22:48:39.593593Z","end":"2026-02-18T22:48:40.262295Z","steps":["trace[1215976108] 'agreement among raft nodes before linearized reading' (duration: 668.536854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.262392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.593586Z","time spent":"668.765405ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:40.264754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.978287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-18T22:48:40.264895Z","caller":"traceutil/trace.go:171","msg":"trace[630874259] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:355; }","duration":"679.139028ms","start":"2026-02-18T22:48:39.585747Z","end":"2026-02-18T22:48:40.264886Z","steps":["trace[630874259] 'agreement among raft nodes before linearized reading' (duration: 678.978607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.265025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.585737Z","time spent":"679.258958ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:40.259728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.860733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6688"} {"level":"warn","ts":"2026-02-18T22:48:40.265261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.700391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-18T22:48:40.265326Z","caller":"traceutil/trace.go:171","msg":"trace[1972507004] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:355; }","duration":"684.771611ms","start":"2026-02-18T22:48:39.580543Z","end":"2026-02-18T22:48:40.265314Z","steps":["trace[1972507004] 'agreement among raft nodes before linearized reading' (duration: 684.698341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.265365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.580536Z","time spent":"684.813331ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-02-18T22:48:40.265473Z","caller":"traceutil/trace.go:171","msg":"trace[519742935] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:355; }","duration":"121.451307ms","start":"2026-02-18T22:48:40.143841Z","end":"2026-02-18T22:48:40.265293Z","steps":["trace[519742935] 'agreement among raft nodes before linearized reading' (duration: 115.805013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.265514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.95676ms","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-18T22:48:40.2656Z","caller":"traceutil/trace.go:171","msg":"trace[1228487566] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:355; }","duration":"693.070241ms","start":"2026-02-18T22:48:39.572521Z","end":"2026-02-18T22:48:40.265591Z","steps":["trace[1228487566] 'agreement among raft nodes before linearized reading' (duration: 692.96006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.265623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.57251Z","time spent":"693.106141ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:40.259307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.398621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4598"} {"level":"info","ts":"2026-02-18T22:48:40.265768Z","caller":"traceutil/trace.go:171","msg":"trace[1857303982] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:355; }","duration":"121.86965ms","start":"2026-02-18T22:48:40.14389Z","end":"2026-02-18T22:48:40.26576Z","steps":["trace[1857303982] 'agreement among raft nodes before linearized reading' (duration: 115.335901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.259823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.019624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-02-18T22:48:40.266069Z","caller":"traceutil/trace.go:171","msg":"trace[1659603231] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:355; }","duration":"122.240621ms","start":"2026-02-18T22:48:40.143781Z","end":"2026-02-18T22:48:40.266022Z","steps":["trace[1659603231] 'agreement among raft nodes before linearized reading' (duration: 115.963644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.265221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.098457ms","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-18T22:48:40.266347Z","caller":"traceutil/trace.go:171","msg":"trace[1331758584] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:355; }","duration":"685.238213ms","start":"2026-02-18T22:48:39.5811Z","end":"2026-02-18T22:48:40.266338Z","steps":["trace[1331758584] 'agreement among raft nodes before linearized reading' (duration: 684.072566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.26644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:39.581094Z","time spent":"685.334953ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-02-18T22:48:40.580218Z","caller":"traceutil/trace.go:171","msg":"trace[928299626] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"299.350181ms","start":"2026-02-18T22:48:40.280842Z","end":"2026-02-18T22:48:40.580192Z","steps":["trace[928299626] 'read index received' (duration: 36.679559ms)","trace[928299626] 'applied index is now lower than readState.Index' (duration: 262.669512ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:40.580479Z","caller":"traceutil/trace.go:171","msg":"trace[684191972] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"299.687734ms","start":"2026-02-18T22:48:40.280732Z","end":"2026-02-18T22:48:40.58042Z","steps":["trace[684191972] 'process raft request' (duration: 254.759885ms)","trace[684191972] 'compare' (duration: 44.442436ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:40.580947Z","caller":"traceutil/trace.go:171","msg":"trace[1346321586] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"297.061238ms","start":"2026-02-18T22:48:40.283874Z","end":"2026-02-18T22:48:40.580935Z","steps":["trace[1346321586] 'process raft request' (duration: 296.195142ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:40.581281Z","caller":"traceutil/trace.go:171","msg":"trace[705525147] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"297.003698ms","start":"2026-02-18T22:48:40.284266Z","end":"2026-02-18T22:48:40.581269Z","steps":["trace[705525147] 'process raft request' (duration: 295.869501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.581557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.69585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-18T22:48:40.581661Z","caller":"traceutil/trace.go:171","msg":"trace[1069945578] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:359; }","duration":"300.81048ms","start":"2026-02-18T22:48:40.280839Z","end":"2026-02-18T22:48:40.581649Z","steps":["trace[1069945578] 'agreement among raft nodes before linearized reading' (duration: 300.667819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:40.581763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.280823Z","time spent":"300.928091ms","remote":"127.0.0.1:51082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4036,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T22:48:41.091683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.536748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:41.091774Z","caller":"traceutil/trace.go:171","msg":"trace[764093656] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"511.37218ms","start":"2026-02-18T22:48:40.580387Z","end":"2026-02-18T22:48:41.091759Z","steps":["trace[764093656] 'read index received' (duration: 372.695471ms)","trace[764093656] 'applied index is now lower than readState.Index' (duration: 138.675759ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:41.091997Z","caller":"traceutil/trace.go:171","msg":"trace[875939065] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"759.563866ms","start":"2026-02-18T22:48:40.332419Z","end":"2026-02-18T22:48:41.091983Z","steps":["trace[875939065] 'process raft request' (duration: 620.654995ms)","trace[875939065] 'compare' (duration: 138.421168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:41.092163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.332406Z","time spent":"759.701716ms","remote":"127.0.0.1:51172","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-18T22:48:41.092392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.747548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:41.092436Z","caller":"traceutil/trace.go:171","msg":"trace[201765606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:360; }","duration":"689.841858ms","start":"2026-02-18T22:48:40.40257Z","end":"2026-02-18T22:48:41.092412Z","steps":["trace[201765606] 'agreement among raft nodes before linearized reading' (duration: 689.789908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.092463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.402548Z","time spent":"689.909578ms","remote":"127.0.0.1:50912","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-18T22:48:41.092662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.296475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-18T22:48:41.092698Z","caller":"traceutil/trace.go:171","msg":"trace[879636644] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:360; }","duration":"771.343116ms","start":"2026-02-18T22:48:40.321339Z","end":"2026-02-18T22:48:41.092682Z","steps":["trace[879636644] 'agreement among raft nodes before linearized reading' (duration: 771.259916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.092728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.321332Z","time spent":"771.389967ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7260,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-18T22:48:41.092926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.235662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-18T22:48:41.092957Z","caller":"traceutil/trace.go:171","msg":"trace[483055456] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:360; }","duration":"809.272012ms","start":"2026-02-18T22:48:40.283674Z","end":"2026-02-18T22:48:41.092946Z","steps":["trace[483055456] 'agreement among raft nodes before linearized reading' (duration: 809.215772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.092984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.283671Z","time spent":"809.305892ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-18T22:48:41.093202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.522904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-18T22:48:41.093232Z","caller":"traceutil/trace.go:171","msg":"trace[1676329723] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:360; }","duration":"809.561105ms","start":"2026-02-18T22:48:40.283663Z","end":"2026-02-18T22:48:41.093224Z","steps":["trace[1676329723] 'agreement among raft nodes before linearized reading' (duration: 809.504725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.093258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.28366Z","time spent":"809.592316ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:41.093392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.718836ms","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-18T22:48:41.093418Z","caller":"traceutil/trace.go:171","msg":"trace[551592224] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:360; }","duration":"809.753047ms","start":"2026-02-18T22:48:40.283657Z","end":"2026-02-18T22:48:41.09341Z","steps":["trace[551592224] 'agreement among raft nodes before linearized reading' (duration: 809.700126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.093445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.283648Z","time spent":"809.790787ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:41.093557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.905037ms","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-18T22:48:41.09358Z","caller":"traceutil/trace.go:171","msg":"trace[1199643494] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:360; }","duration":"809.932957ms","start":"2026-02-18T22:48:40.28364Z","end":"2026-02-18T22:48:41.093573Z","steps":["trace[1199643494] 'agreement among raft nodes before linearized reading' (duration: 809.893427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.093604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.283638Z","time spent":"809.960828ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:41.093712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.071218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-18T22:48:41.093739Z","caller":"traceutil/trace.go:171","msg":"trace[404407178] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:360; }","duration":"810.101398ms","start":"2026-02-18T22:48:40.283628Z","end":"2026-02-18T22:48:41.09373Z","steps":["trace[404407178] 'agreement among raft nodes before linearized reading' (duration: 810.058268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.093764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.283626Z","time spent":"810.130968ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:41.093874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.245179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-18T22:48:41.093898Z","caller":"traceutil/trace.go:171","msg":"trace[275977334] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:360; }","duration":"810.272859ms","start":"2026-02-18T22:48:40.283617Z","end":"2026-02-18T22:48:41.09389Z","steps":["trace[275977334] 'agreement among raft nodes before linearized reading' (duration: 810.233099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.093923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.283615Z","time spent":"810.302759ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-18T22:48:41.09403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.41216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-18T22:48:41.094053Z","caller":"traceutil/trace.go:171","msg":"trace[608107859] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:360; }","duration":"810.44161ms","start":"2026-02-18T22:48:40.283604Z","end":"2026-02-18T22:48:41.094046Z","steps":["trace[608107859] 'agreement among raft nodes before linearized reading' (duration: 810.40307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.094076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.283601Z","time spent":"810.46985ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:41.094181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.597401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-18T22:48:41.094205Z","caller":"traceutil/trace.go:171","msg":"trace[425830] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:360; }","duration":"810.629781ms","start":"2026-02-18T22:48:40.283567Z","end":"2026-02-18T22:48:41.094197Z","steps":["trace[425830] 'agreement among raft nodes before linearized reading' (duration: 810.589991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.09423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.283562Z","time spent":"810.660382ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-18T22:48:41.094335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.393638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-18T22:48:41.094358Z","caller":"traceutil/trace.go:171","msg":"trace[588580853] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:360; }","duration":"813.452608ms","start":"2026-02-18T22:48:40.280899Z","end":"2026-02-18T22:48:41.094351Z","steps":["trace[588580853] 'agreement among raft nodes before linearized reading' (duration: 813.411438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.09438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.280885Z","time spent":"813.490208ms","remote":"127.0.0.1:51082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4036,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T22:48:41.094497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.617839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-18T22:48:41.094522Z","caller":"traceutil/trace.go:171","msg":"trace[1612261589] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:360; }","duration":"813.648779ms","start":"2026-02-18T22:48:40.280866Z","end":"2026-02-18T22:48:41.094515Z","steps":["trace[1612261589] 'agreement among raft nodes before linearized reading' (duration: 813.608508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.094544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.280863Z","time spent":"813.676619ms","remote":"127.0.0.1:51082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4036,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T22:48:41.099576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.063245ms","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-18T22:48:41.099604Z","caller":"traceutil/trace.go:171","msg":"trace[919420345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:364; }","duration":"467.123735ms","start":"2026-02-18T22:48:40.632473Z","end":"2026-02-18T22:48:41.099597Z","steps":["trace[919420345] 'agreement among raft nodes before linearized reading' (duration: 467.065775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.099625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.632458Z","time spent":"467.161805ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:48:41.099712Z","caller":"traceutil/trace.go:171","msg":"trace[898954851] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"504.082966ms","start":"2026-02-18T22:48:40.595622Z","end":"2026-02-18T22:48:41.099705Z","steps":["trace[898954851] 'process raft request' (duration: 503.798504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.099744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.595601Z","time spent":"504.125646ms","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:41.099998Z","caller":"traceutil/trace.go:171","msg":"trace[1621742633] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"504.260747ms","start":"2026-02-18T22:48:40.595727Z","end":"2026-02-18T22:48:41.099988Z","steps":["trace[1621742633] 'process raft request' (duration: 503.741444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.100035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.595721Z","time spent":"504.296158ms","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:41.100169Z","caller":"traceutil/trace.go:171","msg":"trace[548204100] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"504.325637ms","start":"2026-02-18T22:48:40.595836Z","end":"2026-02-18T22:48:41.100161Z","steps":["trace[548204100] 'process raft request' (duration: 503.651454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.100197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.595829Z","time spent":"504.354387ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:41.100261Z","caller":"traceutil/trace.go:171","msg":"trace[225420439] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"488.276591ms","start":"2026-02-18T22:48:40.611978Z","end":"2026-02-18T22:48:41.100255Z","steps":["trace[225420439] 'process raft request' (duration: 487.534947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.100295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.611957Z","time spent":"488.316021ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:41.100391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.596647ms","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-18T22:48:41.100408Z","caller":"traceutil/trace.go:171","msg":"trace[389994460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:364; }","duration":"400.633337ms","start":"2026-02-18T22:48:40.699769Z","end":"2026-02-18T22:48:41.100403Z","steps":["trace[389994460] 'agreement among raft nodes before linearized reading' (duration: 400.600787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.100424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.699761Z","time spent":"400.659187ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:48:41.100496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.087299ms","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-18T22:48:41.100512Z","caller":"traceutil/trace.go:171","msg":"trace[1635384397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:364; }","duration":"401.142599ms","start":"2026-02-18T22:48:40.699364Z","end":"2026-02-18T22:48:41.100506Z","steps":["trace[1635384397] 'agreement among raft nodes before linearized reading' (duration: 401.114029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.10053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.699354Z","time spent":"401.171989ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:48:41.100602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.807435ms","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-18T22:48:41.100618Z","caller":"traceutil/trace.go:171","msg":"trace[1159725652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:364; }","duration":"423.857725ms","start":"2026-02-18T22:48:40.676756Z","end":"2026-02-18T22:48:41.100613Z","steps":["trace[1159725652] 'agreement among raft nodes before linearized reading' (duration: 423.832365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:41.100633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:40.676717Z","time spent":"423.913225ms","remote":"127.0.0.1:51172","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-18T22:48:41.6288Z","caller":"traceutil/trace.go:171","msg":"trace[1209085622] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"512.227474ms","start":"2026-02-18T22:48:41.116543Z","end":"2026-02-18T22:48:41.628771Z","steps":["trace[1209085622] 'process raft request' (duration: 465.217934ms)","trace[1209085622] 'compare' (duration: 46.89727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:41.629029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.116528Z","time spent":"512.412465ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7030,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:42.062974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.887478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:42.063835Z","caller":"traceutil/trace.go:171","msg":"trace[610163484] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:371; }","duration":"665.051623ms","start":"2026-02-18T22:48:41.398768Z","end":"2026-02-18T22:48:42.06382Z","steps":["trace[610163484] 'read index received' (duration: 183.003761ms)","trace[610163484] 'applied index is now lower than readState.Index' (duration: 482.047082ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:42.064088Z","caller":"traceutil/trace.go:171","msg":"trace[1416213564] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"944.112548ms","start":"2026-02-18T22:48:41.119964Z","end":"2026-02-18T22:48:42.064076Z","steps":["trace[1416213564] 'process raft request' (duration: 631.043523ms)","trace[1416213564] 'compare' (duration: 311.780528ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:42.064155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.119951Z","time spent":"944.170828ms","remote":"127.0.0.1:51172","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-18T22:48:42.064585Z","caller":"traceutil/trace.go:171","msg":"trace[1783433494] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"944.379249ms","start":"2026-02-18T22:48:41.120194Z","end":"2026-02-18T22:48:42.064574Z","steps":["trace[1783433494] 'process raft request' (duration: 942.86912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.064649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.120189Z","time spent":"944.424289ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:42.064856Z","caller":"traceutil/trace.go:171","msg":"trace[1510197234] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"941.055829ms","start":"2026-02-18T22:48:41.12379Z","end":"2026-02-18T22:48:42.064846Z","steps":["trace[1510197234] 'process raft request' (duration: 939.32178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.064906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.123772Z","time spent":"941.106829ms","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:42.065064Z","caller":"traceutil/trace.go:171","msg":"trace[856879178] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"940.214213ms","start":"2026-02-18T22:48:41.124841Z","end":"2026-02-18T22:48:42.065055Z","steps":["trace[856879178] 'process raft request' (duration: 938.304503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.065104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.124833Z","time spent":"940.252444ms","remote":"127.0.0.1:51174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:42.065205Z","caller":"traceutil/trace.go:171","msg":"trace[1101183365] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"940.251064ms","start":"2026-02-18T22:48:41.124946Z","end":"2026-02-18T22:48:42.065197Z","steps":["trace[1101183365] 'process raft request' (duration: 938.236363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.065247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.124943Z","time spent":"940.285945ms","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:42.065804Z","caller":"traceutil/trace.go:171","msg":"trace[1465384382] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"940.814649ms","start":"2026-02-18T22:48:41.124977Z","end":"2026-02-18T22:48:42.065792Z","steps":["trace[1465384382] 'process raft request' (duration: 938.234443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.065871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.124974Z","time spent":"940.866519ms","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:42.065988Z","caller":"traceutil/trace.go:171","msg":"trace[2017694736] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"939.527391ms","start":"2026-02-18T22:48:41.126453Z","end":"2026-02-18T22:48:42.06598Z","steps":["trace[2017694736] 'process raft request' (duration: 936.783694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.066033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.126447Z","time spent":"939.562071ms","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:42.066219Z","caller":"traceutil/trace.go:171","msg":"trace[697980325] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"939.536271ms","start":"2026-02-18T22:48:41.126674Z","end":"2026-02-18T22:48:42.066211Z","steps":["trace[697980325] 'process raft request' (duration: 936.589813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.066274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.126668Z","time spent":"939.582821ms","remote":"127.0.0.1:51028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:42.066386Z","caller":"traceutil/trace.go:171","msg":"trace[1330831963] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"938.656265ms","start":"2026-02-18T22:48:41.127722Z","end":"2026-02-18T22:48:42.066378Z","steps":["trace[1330831963] 'process raft request' (duration: 935.569567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.066437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.127715Z","time spent":"938.698445ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4715,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:42.06654Z","caller":"traceutil/trace.go:171","msg":"trace[2083284972] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"938.345244ms","start":"2026-02-18T22:48:41.128188Z","end":"2026-02-18T22:48:42.066533Z","steps":["trace[2083284972] 'process raft request' (duration: 935.130055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.066585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.128182Z","time spent":"938.384424ms","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:42.066685Z","caller":"traceutil/trace.go:171","msg":"trace[611336948] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"938.385233ms","start":"2026-02-18T22:48:41.12829Z","end":"2026-02-18T22:48:42.066676Z","steps":["trace[611336948] 'process raft request' (duration: 935.049314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.066733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.128286Z","time spent":"938.424894ms","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:42.066907Z","caller":"traceutil/trace.go:171","msg":"trace[2031939436] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"938.324393ms","start":"2026-02-18T22:48:41.128575Z","end":"2026-02-18T22:48:42.066899Z","steps":["trace[2031939436] 'process raft request' (duration: 934.791502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.066956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.128569Z","time spent":"938.367224ms","remote":"127.0.0.1:51404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:42.067108Z","caller":"traceutil/trace.go:171","msg":"trace[555630366] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"937.73365ms","start":"2026-02-18T22:48:41.129351Z","end":"2026-02-18T22:48:42.067084Z","steps":["trace[555630366] 'process raft request' (duration: 934.040058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.067148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.129345Z","time spent":"937.78503ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:42.067243Z","caller":"traceutil/trace.go:171","msg":"trace[2120577069] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"937.615969ms","start":"2026-02-18T22:48:41.129619Z","end":"2026-02-18T22:48:42.067235Z","steps":["trace[2120577069] 'process raft request' (duration: 933.802986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.067284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.129614Z","time spent":"937.648409ms","remote":"127.0.0.1:51172","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-18T22:48:42.067378Z","caller":"traceutil/trace.go:171","msg":"trace[1067744397] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"937.047975ms","start":"2026-02-18T22:48:41.130323Z","end":"2026-02-18T22:48:42.067371Z","steps":["trace[1067744397] 'process raft request' (duration: 933.158433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.067452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.130317Z","time spent":"937.081776ms","remote":"127.0.0.1:51028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:42.067562Z","caller":"traceutil/trace.go:171","msg":"trace[510984547] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"936.884584ms","start":"2026-02-18T22:48:41.13067Z","end":"2026-02-18T22:48:42.067554Z","steps":["trace[510984547] 'process raft request' (duration: 932.841741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.067614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.130664Z","time spent":"936.921635ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6805,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:42.067778Z","caller":"traceutil/trace.go:171","msg":"trace[1402896320] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"936.376522ms","start":"2026-02-18T22:48:41.131393Z","end":"2026-02-18T22:48:42.067769Z","steps":["trace[1402896320] 'process raft request' (duration: 932.145717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.067826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.131386Z","time spent":"936.415662ms","remote":"127.0.0.1:51166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:42.067998Z","caller":"traceutil/trace.go:171","msg":"trace[223509877] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"935.830048ms","start":"2026-02-18T22:48:41.132158Z","end":"2026-02-18T22:48:42.067988Z","steps":["trace[223509877] 'process raft request' (duration: 931.409552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.068045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.132153Z","time spent":"935.867519ms","remote":"127.0.0.1:51394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3737,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:42.068145Z","caller":"traceutil/trace.go:171","msg":"trace[347212409] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"935.295716ms","start":"2026-02-18T22:48:41.132842Z","end":"2026-02-18T22:48:42.068138Z","steps":["trace[347212409] 'process raft request' (duration: 930.756358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.068188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.132836Z","time spent":"935.328796ms","remote":"127.0.0.1:51172","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-18T22:48:42.069474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.686347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:42.069657Z","caller":"traceutil/trace.go:171","msg":"trace[1539556090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:385; }","duration":"670.908148ms","start":"2026-02-18T22:48:41.398734Z","end":"2026-02-18T22:48:42.069642Z","steps":["trace[1539556090] 'agreement among raft nodes before linearized reading' (duration: 670.673597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.069794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.398716Z","time spent":"671.068439ms","remote":"127.0.0.1:50902","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-18T22:48:42.07008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.559381ms","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-18T22:48:42.070182Z","caller":"traceutil/trace.go:171","msg":"trace[2090071809] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:385; }","duration":"659.683951ms","start":"2026-02-18T22:48:41.41049Z","end":"2026-02-18T22:48:42.070174Z","steps":["trace[2090071809] 'agreement among raft nodes before linearized reading' (duration: 659.55423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.0703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.410478Z","time spent":"659.813572ms","remote":"127.0.0.1:50934","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-18T22:48:42.068291Z","caller":"traceutil/trace.go:171","msg":"trace[89466208] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"935.363715ms","start":"2026-02-18T22:48:41.132919Z","end":"2026-02-18T22:48:42.068282Z","steps":["trace[89466208] 'process raft request' (duration: 930.873108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:42.074073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:41.132915Z","time spent":"941.13677ms","remote":"127.0.0.1:51172","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-18T22:48:44.400183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999912154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T22:48:44.400248Z","caller":"traceutil/trace.go:171","msg":"trace[2056111387] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000026605s","start":"2026-02-18T22:48:42.400206Z","end":"2026-02-18T22:48:44.400233Z","steps":["trace[2056111387] 'agreement among raft nodes before linearized reading' (duration: 1.999943854s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:44.400289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.400187Z","time spent":"2.000096235s","remote":"127.0.0.1:50902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 22:48:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T22:48:44.436517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.862239365s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-18T22:48:44.436845Z","caller":"traceutil/trace.go:171","msg":"trace[693786051] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:393; }","duration":"2.345563595s","start":"2026-02-18T22:48:42.091269Z","end":"2026-02-18T22:48:44.436832Z","steps":["trace[693786051] 'read index received' (duration: 25.572452ms)","trace[693786051] 'applied index is now lower than readState.Index' (duration: 2.319990473s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:48:44.436892Z","caller":"traceutil/trace.go:171","msg":"trace[624200728] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"2.349887331s","start":"2026-02-18T22:48:42.086999Z","end":"2026-02-18T22:48:44.436886Z","steps":["trace[624200728] 'process raft request' (duration: 2.349581459s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:44.436936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.08699Z","time spent":"2.349916431s","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:44.437124Z","caller":"traceutil/trace.go:171","msg":"trace[157618315] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"2.350067082s","start":"2026-02-18T22:48:42.087049Z","end":"2026-02-18T22:48:44.437116Z","steps":["trace[157618315] 'process raft request' (duration: 2.34959047s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:44.437154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.087046Z","time spent":"2.350094132s","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3355,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:44.437229Z","caller":"traceutil/trace.go:171","msg":"trace[1788257464] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"2.346058898s","start":"2026-02-18T22:48:42.09116Z","end":"2026-02-18T22:48:44.437219Z","steps":["trace[1788257464] 'process raft request' (duration: 2.345503675s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:44.43707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.084145Z","time spent":"2.352894539s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T22:48:44.437294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.091152Z","time spent":"2.346122148s","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":699,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:44.43747Z","caller":"traceutil/trace.go:171","msg":"trace[1704727374] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"2.345031812s","start":"2026-02-18T22:48:42.092431Z","end":"2026-02-18T22:48:44.437463Z","steps":["trace[1704727374] 'process raft request' (duration: 2.344293298s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:44.437512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.092425Z","time spent":"2.345062302s","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:44.437619Z","caller":"traceutil/trace.go:171","msg":"trace[213939707] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"2.343974505s","start":"2026-02-18T22:48:42.093639Z","end":"2026-02-18T22:48:44.437614Z","steps":["trace[213939707] 'process raft request' (duration: 2.34311545s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:44.437647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.093633Z","time spent":"2.343999935s","remote":"127.0.0.1:51028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:44.437716Z","caller":"traceutil/trace.go:171","msg":"trace[1613766758] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"2.343887685s","start":"2026-02-18T22:48:42.093823Z","end":"2026-02-18T22:48:44.437711Z","steps":["trace[1613766758] 'process raft request' (duration: 2.342951139s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:44.437828Z","caller":"traceutil/trace.go:171","msg":"trace[211198409] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"2.343486932s","start":"2026-02-18T22:48:42.094329Z","end":"2026-02-18T22:48:44.437816Z","steps":["trace[211198409] 'process raft request' (duration: 2.342468046s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:44.438283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.094323Z","time spent":"2.343907286s","remote":"127.0.0.1:51394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:44.438373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.347104094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-02-18T22:48:44.438398Z","caller":"traceutil/trace.go:171","msg":"trace[998422699] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:393; }","duration":"2.347137514s","start":"2026-02-18T22:48:42.091252Z","end":"2026-02-18T22:48:44.438389Z","steps":["trace[998422699] 'agreement among raft nodes before linearized reading' (duration: 2.347098064s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:44.438413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.091247Z","time spent":"2.347162955s","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":2131,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-02-18T22:48:44.447302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:42.093805Z","time spent":"2.343924035s","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:44.692412Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.049974054s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:48:45.896964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203967634s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:45.898732Z","caller":"traceutil/trace.go:171","msg":"trace[880233196] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"1.44415854s","start":"2026-02-18T22:48:44.454555Z","end":"2026-02-18T22:48:45.898714Z","steps":["trace[880233196] 'process raft request' (duration: 1.444120939s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.898819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.454548Z","time spent":"1.44423101s","remote":"127.0.0.1:51246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:45.899357Z","caller":"traceutil/trace.go:171","msg":"trace[1464182002] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"2.257003847s","start":"2026-02-18T22:48:43.642332Z","end":"2026-02-18T22:48:45.899336Z","steps":["trace[1464182002] 'process raft request' (duration: 1.050598619s)","trace[1464182002] 'compare' (duration: 1.202522566s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:45.899557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:43.642308Z","time spent":"2.257099927s","remote":"127.0.0.1:51172","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-18T22:48:45.90022Z","caller":"traceutil/trace.go:171","msg":"trace[851679179] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"1.463340532s","start":"2026-02-18T22:48:44.436866Z","end":"2026-02-18T22:48:45.900206Z","steps":["trace[851679179] 'read index received' (duration: 256.23848ms)","trace[851679179] 'applied index is now lower than readState.Index' (duration: 1.207100432s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:45.901194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.803874491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6820"} {"level":"info","ts":"2026-02-18T22:48:45.901359Z","caller":"traceutil/trace.go:171","msg":"trace[547679344] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:403; }","duration":"2.804958397s","start":"2026-02-18T22:48:43.096383Z","end":"2026-02-18T22:48:45.901342Z","steps":["trace[547679344] 'agreement among raft nodes before linearized reading' (duration: 2.803871611s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:45.90497Z","caller":"traceutil/trace.go:171","msg":"trace[64049437] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"1.457772509s","start":"2026-02-18T22:48:44.447175Z","end":"2026-02-18T22:48:45.904948Z","steps":["trace[64049437] 'process raft request' (duration: 1.449870942s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.905487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.447161Z","time spent":"1.45792973s","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:48:45.903854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:43.096369Z","time spent":"2.80716972s","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6843,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-18T22:48:45.905827Z","caller":"traceutil/trace.go:171","msg":"trace[1242912920] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"1.458592454s","start":"2026-02-18T22:48:44.447222Z","end":"2026-02-18T22:48:45.905814Z","steps":["trace[1242912920] 'process raft request' (duration: 1.449875893s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.906134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.447218Z","time spent":"1.458870566s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:45.906486Z","caller":"traceutil/trace.go:171","msg":"trace[1924441830] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"1.457752929s","start":"2026-02-18T22:48:44.448722Z","end":"2026-02-18T22:48:45.906474Z","steps":["trace[1924441830] 'process raft request' (duration: 1.448410384s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.906671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.448706Z","time spent":"1.45788161s","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3401,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:45.907002Z","caller":"traceutil/trace.go:171","msg":"trace[1176230801] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"1.455496136s","start":"2026-02-18T22:48:44.451457Z","end":"2026-02-18T22:48:45.906953Z","steps":["trace[1176230801] 'process raft request' (duration: 1.447183157s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.907969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.451451Z","time spent":"1.456481862s","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4019,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:48:45.907425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506683158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:45.914958Z","caller":"traceutil/trace.go:171","msg":"trace[372199925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:403; }","duration":"1.511444727s","start":"2026-02-18T22:48:44.400691Z","end":"2026-02-18T22:48:45.912135Z","steps":["trace[372199925] 'agreement among raft nodes before linearized reading' (duration: 1.506693368s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.915067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.400672Z","time spent":"1.514366264s","remote":"127.0.0.1:50912","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-18T22:48:45.907483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.811880646s","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-18T22:48:45.920189Z","caller":"traceutil/trace.go:171","msg":"trace[1107427278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"1.824626692s","start":"2026-02-18T22:48:44.095551Z","end":"2026-02-18T22:48:45.920178Z","steps":["trace[1107427278] 'agreement among raft nodes before linearized reading' (duration: 1.811905247s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.920278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.095541Z","time spent":"1.824713452s","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:48:45.907516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.815261576s","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-18T22:48:45.920561Z","caller":"traceutil/trace.go:171","msg":"trace[457643587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:403; }","duration":"1.828326273s","start":"2026-02-18T22:48:44.092226Z","end":"2026-02-18T22:48:45.920553Z","steps":["trace[457643587] 'agreement among raft nodes before linearized reading' (duration: 1.815270926s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.920636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.092216Z","time spent":"1.828410043s","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:48:45.907546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.825267154s","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-18T22:48:45.920938Z","caller":"traceutil/trace.go:171","msg":"trace[2140551646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:403; }","duration":"1.838656413s","start":"2026-02-18T22:48:44.082273Z","end":"2026-02-18T22:48:45.92093Z","steps":["trace[2140551646] 'agreement among raft nodes before linearized reading' (duration: 1.825255404s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.921011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.082221Z","time spent":"1.838780703s","remote":"127.0.0.1:51172","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-18T22:48:45.907577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.825593657s","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-18T22:48:45.921351Z","caller":"traceutil/trace.go:171","msg":"trace[278843051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"1.839383518s","start":"2026-02-18T22:48:44.081944Z","end":"2026-02-18T22:48:45.921328Z","steps":["trace[278843051] 'agreement among raft nodes before linearized reading' (duration: 1.825615737s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.921429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.081925Z","time spent":"1.839491979s","remote":"127.0.0.1:51172","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-18T22:48:45.921597Z","caller":"traceutil/trace.go:171","msg":"trace[1916741269] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"1.47146353s","start":"2026-02-18T22:48:44.450125Z","end":"2026-02-18T22:48:45.921588Z","steps":["trace[1916741269] 'process raft request' (duration: 1.447042136s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.921699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.450119Z","time spent":"1.47155272s","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:45.921824Z","caller":"traceutil/trace.go:171","msg":"trace[636075920] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"1.47166248s","start":"2026-02-18T22:48:44.450156Z","end":"2026-02-18T22:48:45.921819Z","steps":["trace[636075920] 'process raft request' (duration: 1.448378584s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.921883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.450152Z","time spent":"1.47171744s","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:48:45.922077Z","caller":"traceutil/trace.go:171","msg":"trace[1195559222] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"1.471886711s","start":"2026-02-18T22:48:44.450184Z","end":"2026-02-18T22:48:45.92207Z","steps":["trace[1195559222] 'process raft request' (duration: 1.448395574s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.92217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.45018Z","time spent":"1.471943122s","remote":"127.0.0.1:51378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2864,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:48:45.922324Z","caller":"traceutil/trace.go:171","msg":"trace[348394] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"1.47153034s","start":"2026-02-18T22:48:44.450787Z","end":"2026-02-18T22:48:45.922317Z","steps":["trace[348394] 'process raft request' (duration: 1.447826021s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:45.922409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:44.450781Z","time spent":"1.47159045s","remote":"127.0.0.1:51028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:48:45.907604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.638071461s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:45.922939Z","caller":"traceutil/trace.go:171","msg":"trace[836902317] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:403; }","duration":"2.65323672s","start":"2026-02-18T22:48:43.269525Z","end":"2026-02-18T22:48:45.922762Z","steps":["trace[836902317] 'agreement among raft nodes before linearized reading' (duration: 2.638065951s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:48:52.350001Z","caller":"traceutil/trace.go:171","msg":"trace[1543361620] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"349.56837ms","start":"2026-02-18T22:48:52.000418Z","end":"2026-02-18T22:48:52.349987Z","steps":["trace[1543361620] 'process raft request' (duration: 349.489397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:52.350096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:52.000406Z","time spent":"349.638703ms","remote":"127.0.0.1:51172","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-18T22:48:52.543165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.587185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:48:52.543643Z","caller":"traceutil/trace.go:171","msg":"trace[1229580566] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"542.95242ms","start":"2026-02-18T22:48:52.000666Z","end":"2026-02-18T22:48:52.543618Z","steps":["trace[1229580566] 'process raft request' (duration: 440.724601ms)","trace[1229580566] 'compare' (duration: 101.351375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:52.544464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:52.000659Z","time spent":"543.728159ms","remote":"127.0.0.1:51172","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-18T22:48:52.543694Z","caller":"traceutil/trace.go:171","msg":"trace[1504616440] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"541.68701ms","start":"2026-02-18T22:48:52.001953Z","end":"2026-02-18T22:48:52.54364Z","steps":["trace[1504616440] 'read index received' (duration: 348.411835ms)","trace[1504616440] 'applied index is now lower than readState.Index' (duration: 193.272875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:48:52.54543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.222776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:48:52.545481Z","caller":"traceutil/trace.go:171","msg":"trace[2035489982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"144.296399ms","start":"2026-02-18T22:48:52.401171Z","end":"2026-02-18T22:48:52.545468Z","steps":["trace[2035489982] 'agreement among raft nodes before linearized reading' (duration: 144.221686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:52.543936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.972471ms","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-18T22:48:52.546065Z","caller":"traceutil/trace.go:171","msg":"trace[372448422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:434; }","duration":"544.113164ms","start":"2026-02-18T22:48:52.001933Z","end":"2026-02-18T22:48:52.546047Z","steps":["trace[372448422] 'agreement among raft nodes before linearized reading' (duration: 541.9401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:52.54666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:52.001927Z","time spent":"544.564661ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:48:52.546361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.343793ms","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-18T22:48:52.5473Z","caller":"traceutil/trace.go:171","msg":"trace[610999111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:434; }","duration":"545.31ms","start":"2026-02-18T22:48:52.001974Z","end":"2026-02-18T22:48:52.547284Z","steps":["trace[610999111] 'agreement among raft nodes before linearized reading' (duration: 543.280802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:48:52.547602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:48:52.00197Z","time spent":"545.613431ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:48:52.547505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.705006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-18T22:48:52.548154Z","caller":"traceutil/trace.go:171","msg":"trace[2144917078] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:434; }","duration":"175.396003ms","start":"2026-02-18T22:48:52.372743Z","end":"2026-02-18T22:48:52.548139Z","steps":["trace[2144917078] 'agreement among raft nodes before linearized reading' (duration: 174.692025ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:05.702709Z","caller":"traceutil/trace.go:171","msg":"trace[135154597] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"101.611509ms","start":"2026-02-18T22:49:05.601077Z","end":"2026-02-18T22:49:05.702688Z","steps":["trace[135154597] 'process raft request' (duration: 98.292889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:07.190792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.948757ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:49:07.191666Z","caller":"traceutil/trace.go:171","msg":"trace[1937396377] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"189.142831ms","start":"2026-02-18T22:49:07.002502Z","end":"2026-02-18T22:49:07.191645Z","steps":["trace[1937396377] 'process raft request' (duration: 188.408506ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:10.313618Z","caller":"traceutil/trace.go:171","msg":"trace[1642173947] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:565; }","duration":"113.078524ms","start":"2026-02-18T22:49:10.200519Z","end":"2026-02-18T22:49:10.313598Z","steps":["trace[1642173947] 'read index received' (duration: 349.481µs)","trace[1642173947] 'applied index is now lower than readState.Index' (duration: 112.728073ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:10.313722Z","caller":"traceutil/trace.go:171","msg":"trace[1219138643] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"162.052164ms","start":"2026-02-18T22:49:10.151659Z","end":"2026-02-18T22:49:10.313712Z","steps":["trace[1219138643] 'process raft request' (duration: 161.833487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:10.313952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.415355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-7h5xk\" ","response":"range_response_count:1 size:4639"} {"level":"info","ts":"2026-02-18T22:49:10.313983Z","caller":"traceutil/trace.go:171","msg":"trace[1935809688] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-7h5xk; range_end:; response_count:1; response_revision:553; }","duration":"113.486217ms","start":"2026-02-18T22:49:10.200487Z","end":"2026-02-18T22:49:10.313973Z","steps":["trace[1935809688] 'agreement among raft nodes before linearized reading' (duration: 113.364803ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:10.581163Z","caller":"traceutil/trace.go:171","msg":"trace[1631028470] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"261.402567ms","start":"2026-02-18T22:49:10.319741Z","end":"2026-02-18T22:49:10.581144Z","steps":["trace[1631028470] 'process raft request' (duration: 219.50809ms)","trace[1631028470] 'compare' (duration: 41.810024ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:10.695052Z","caller":"traceutil/trace.go:171","msg":"trace[1781325775] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:568; }","duration":"295.837448ms","start":"2026-02-18T22:49:10.399204Z","end":"2026-02-18T22:49:10.695041Z","steps":["trace[1781325775] 'read index received' (duration: 140.008461ms)","trace[1781325775] 'applied index is now lower than readState.Index' (duration: 155.828547ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:10.695184Z","caller":"traceutil/trace.go:171","msg":"trace[1804154355] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"369.10023ms","start":"2026-02-18T22:49:10.326078Z","end":"2026-02-18T22:49:10.695179Z","steps":["trace[1804154355] 'process raft request' (duration: 366.003163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:10.695241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:10.326069Z","time spent":"369.128052ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4807,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:49:10.695371Z","caller":"traceutil/trace.go:171","msg":"trace[1244355222] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"351.946752ms","start":"2026-02-18T22:49:10.343419Z","end":"2026-02-18T22:49:10.695366Z","steps":["trace[1244355222] 'process raft request' (duration: 351.577311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:10.695398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:10.343374Z","time spent":"352.008884ms","remote":"127.0.0.1:50942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:49:10.695454Z","caller":"traceutil/trace.go:171","msg":"trace[1019055017] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"203.811365ms","start":"2026-02-18T22:49:10.491639Z","end":"2026-02-18T22:49:10.695451Z","steps":["trace[1019055017] 'process raft request' (duration: 203.384912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:10.695518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.347595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:49:10.695537Z","caller":"traceutil/trace.go:171","msg":"trace[1216540981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"296.400936ms","start":"2026-02-18T22:49:10.39913Z","end":"2026-02-18T22:49:10.695531Z","steps":["trace[1216540981] 'agreement among raft nodes before linearized reading' (duration: 296.371675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:11.989234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810449658218931,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:49:12.094842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:11.415096Z","time spent":"679.741301ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T22:49:12.101091Z","caller":"traceutil/trace.go:171","msg":"trace[1092791467] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"612.351873ms","start":"2026-02-18T22:49:11.488711Z","end":"2026-02-18T22:49:12.101063Z","steps":["trace[1092791467] 'read index received' (duration: 606.752931ms)","trace[1092791467] 'applied index is now lower than readState.Index' (duration: 5.485539ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:12.101269Z","caller":"traceutil/trace.go:171","msg":"trace[1868619720] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"352.232566ms","start":"2026-02-18T22:49:11.749025Z","end":"2026-02-18T22:49:12.101258Z","steps":["trace[1868619720] 'process raft request' (duration: 349.936275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:12.101377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:11.749004Z","time spent":"352.297609ms","remote":"127.0.0.1:51172","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-18T22:49:12.104888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.190442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:49:12.104923Z","caller":"traceutil/trace.go:171","msg":"trace[1506015958] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:581; }","duration":"616.248063ms","start":"2026-02-18T22:49:11.488662Z","end":"2026-02-18T22:49:12.10491Z","steps":["trace[1506015958] 'agreement among raft nodes before linearized reading' (duration: 615.608733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:12.104961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:11.488648Z","time spent":"616.306256ms","remote":"127.0.0.1:50942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:49:12.111464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.977174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:49:12.1115Z","caller":"traceutil/trace.go:171","msg":"trace[1408301639] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:582; }","duration":"423.046155ms","start":"2026-02-18T22:49:11.688445Z","end":"2026-02-18T22:49:12.111491Z","steps":["trace[1408301639] 'agreement among raft nodes before linearized reading' (duration: 422.899211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:12.111622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:11.688433Z","time spent":"423.18221ms","remote":"127.0.0.1:50942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-18T22:49:12.114393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.788327ms","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-18T22:49:12.114443Z","caller":"traceutil/trace.go:171","msg":"trace[1103621584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:582; }","duration":"349.86763ms","start":"2026-02-18T22:49:11.764563Z","end":"2026-02-18T22:49:12.114431Z","steps":["trace[1103621584] 'agreement among raft nodes before linearized reading' (duration: 347.530398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:12.114476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:11.764548Z","time spent":"349.921471ms","remote":"127.0.0.1:51172","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-18T22:49:12.115891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.764088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-18T22:49:12.11592Z","caller":"traceutil/trace.go:171","msg":"trace[28513275] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:582; }","duration":"226.814044ms","start":"2026-02-18T22:49:11.889098Z","end":"2026-02-18T22:49:12.115912Z","steps":["trace[28513275] 'agreement among raft nodes before linearized reading' (duration: 222.744728ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:12.227515Z","caller":"traceutil/trace.go:171","msg":"trace[270787828] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"106.107872ms","start":"2026-02-18T22:49:12.121382Z","end":"2026-02-18T22:49:12.22749Z","steps":["trace[270787828] 'read index received' (duration: 105.806073ms)","trace[270787828] 'applied index is now lower than readState.Index' (duration: 300.879µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:49:12.227822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.421052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-18T22:49:12.228557Z","caller":"traceutil/trace.go:171","msg":"trace[469983274] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:583; }","duration":"107.176696ms","start":"2026-02-18T22:49:12.121362Z","end":"2026-02-18T22:49:12.228538Z","steps":["trace[469983274] 'agreement among raft nodes before linearized reading' (duration: 106.235307ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:12.228651Z","caller":"traceutil/trace.go:171","msg":"trace[2060813642] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"110.639292ms","start":"2026-02-18T22:49:12.118Z","end":"2026-02-18T22:49:12.228639Z","steps":["trace[2060813642] 'process raft request' (duration: 109.27542ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:16.611784Z","caller":"traceutil/trace.go:171","msg":"trace[461645215] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"201.344586ms","start":"2026-02-18T22:49:16.410421Z","end":"2026-02-18T22:49:16.611766Z","steps":["trace[461645215] 'process raft request' (duration: 201.239813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:20.902704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810449658219064,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:49:20.998711Z","caller":"traceutil/trace.go:171","msg":"trace[1437590268] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"597.053743ms","start":"2026-02-18T22:49:20.401636Z","end":"2026-02-18T22:49:20.99869Z","steps":["trace[1437590268] 'read index received' (duration: 596.783996ms)","trace[1437590268] 'applied index is now lower than readState.Index' (duration: 268.597µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:20.999013Z","caller":"traceutil/trace.go:171","msg":"trace[104196065] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"757.798846ms","start":"2026-02-18T22:49:20.241202Z","end":"2026-02-18T22:49:20.999001Z","steps":["trace[104196065] 'process raft request' (duration: 757.27733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:20.999111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:20.241173Z","time spent":"757.870247ms","remote":"127.0.0.1:51092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:49:20.99928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.63975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:49:20.999309Z","caller":"traceutil/trace.go:171","msg":"trace[395413931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"597.684711ms","start":"2026-02-18T22:49:20.401615Z","end":"2026-02-18T22:49:20.9993Z","steps":["trace[395413931] 'agreement among raft nodes before linearized reading' (duration: 597.63743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:20.999335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:20.401603Z","time spent":"597.726392ms","remote":"127.0.0.1:50902","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-18T22:49:20.999534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.732572ms","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-18T22:49:20.999562Z","caller":"traceutil/trace.go:171","msg":"trace[1833303501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:636; }","duration":"376.787613ms","start":"2026-02-18T22:49:20.622766Z","end":"2026-02-18T22:49:20.999553Z","steps":["trace[1833303501] 'agreement among raft nodes before linearized reading' (duration: 376.68273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:20.999587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:20.622748Z","time spent":"376.833465ms","remote":"127.0.0.1:51172","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-18T22:49:21.00002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.527557ms","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-18T22:49:21.000076Z","caller":"traceutil/trace.go:171","msg":"trace[2083088616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"115.6061ms","start":"2026-02-18T22:49:20.884461Z","end":"2026-02-18T22:49:21.000067Z","steps":["trace[2083088616] 'agreement among raft nodes before linearized reading' (duration: 115.508987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:22.37841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194462452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:49:22.37846Z","caller":"traceutil/trace.go:171","msg":"trace[147880742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.194539363s","start":"2026-02-18T22:49:21.183908Z","end":"2026-02-18T22:49:22.378447Z","steps":["trace[147880742] 'range keys from in-memory index tree' (duration: 1.194357549s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:22.378481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:21.183895Z","time spent":"1.194582144s","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:49:22.378687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008180204s","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-18T22:49:22.378703Z","caller":"traceutil/trace.go:171","msg":"trace[1741342775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.008219486s","start":"2026-02-18T22:49:21.370477Z","end":"2026-02-18T22:49:22.378697Z","steps":["trace[1741342775] 'range keys from in-memory index tree' (duration: 1.008116763s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:22.378714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:21.370463Z","time spent":"1.008248837s","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:49:22.37879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.863286ms","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-18T22:49:22.378802Z","caller":"traceutil/trace.go:171","msg":"trace[302066557] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:639; }","duration":"964.897677ms","start":"2026-02-18T22:49:21.4139Z","end":"2026-02-18T22:49:22.378798Z","steps":["trace[302066557] 'range keys from in-memory index tree' (duration: 964.794954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:22.378813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:21.413885Z","time spent":"964.925278ms","remote":"127.0.0.1:50934","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-18T22:49:22.819794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:22.381128Z","time spent":"438.662108ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T22:49:23.506687Z","caller":"traceutil/trace.go:171","msg":"trace[93427301] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"1.107882247s","start":"2026-02-18T22:49:22.398785Z","end":"2026-02-18T22:49:23.506668Z","steps":["trace[93427301] 'read index received' (duration: 421.026592ms)","trace[93427301] 'applied index is now lower than readState.Index' (duration: 686.854765ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:49:23.507573Z","caller":"traceutil/trace.go:171","msg":"trace[147025603] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.124038612s","start":"2026-02-18T22:49:22.38352Z","end":"2026-02-18T22:49:23.507558Z","steps":["trace[147025603] 'process raft request' (duration: 1.122960373s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:23.507871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.064448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-18T22:49:23.507873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:22.383511Z","time spent":"1.124104494s","remote":"127.0.0.1:51172","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-18T22:49:23.507911Z","caller":"traceutil/trace.go:171","msg":"trace[124814557] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"685.047594ms","start":"2026-02-18T22:49:22.82286Z","end":"2026-02-18T22:49:23.507908Z","steps":["trace[124814557] 'process raft request' (duration: 684.89706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:23.50794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:22.822843Z","time spent":"685.079455ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:49:23.508045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.906711ms","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-18T22:49:23.508057Z","caller":"traceutil/trace.go:171","msg":"trace[709527771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:643; }","duration":"448.928043ms","start":"2026-02-18T22:49:23.059126Z","end":"2026-02-18T22:49:23.508054Z","steps":["trace[709527771] 'agreement among raft nodes before linearized reading' (duration: 448.890802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:23.508072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:23.059121Z","time spent":"448.947293ms","remote":"127.0.0.1:51172","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-18T22:49:23.508124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.638707ms","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-18T22:49:23.508134Z","caller":"traceutil/trace.go:171","msg":"trace[1564088898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"451.658807ms","start":"2026-02-18T22:49:23.056472Z","end":"2026-02-18T22:49:23.508131Z","steps":["trace[1564088898] 'agreement among raft nodes before linearized reading' (duration: 451.628066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:23.508145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:23.056468Z","time spent":"451.674787ms","remote":"127.0.0.1:51172","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-18T22:49:23.508253Z","caller":"traceutil/trace.go:171","msg":"trace[1877461150] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.124591487s","start":"2026-02-18T22:49:22.383657Z","end":"2026-02-18T22:49:23.508248Z","steps":["trace[1877461150] 'process raft request' (duration: 1.122939533s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:23.508278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:22.383631Z","time spent":"1.124632098s","remote":"127.0.0.1:51172","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-18T22:49:23.508329Z","caller":"traceutil/trace.go:171","msg":"trace[828741609] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.10983182s","start":"2026-02-18T22:49:22.398492Z","end":"2026-02-18T22:49:23.508323Z","steps":["trace[828741609] 'process raft request' (duration: 1.108143425s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:23.508351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:22.398477Z","time spent":"1.109862161s","remote":"127.0.0.1:51172","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-18T22:49:23.508388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109603334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:49:23.508398Z","caller":"traceutil/trace.go:171","msg":"trace[201154478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.109613084s","start":"2026-02-18T22:49:22.398782Z","end":"2026-02-18T22:49:23.508395Z","steps":["trace[201154478] 'agreement among raft nodes before linearized reading' (duration: 1.109595014s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:23.508409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:22.398748Z","time spent":"1.109659245s","remote":"127.0.0.1:50902","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-18T22:49:23.507894Z","caller":"traceutil/trace.go:171","msg":"trace[601211677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"456.110159ms","start":"2026-02-18T22:49:23.051778Z","end":"2026-02-18T22:49:23.507888Z","steps":["trace[601211677] 'agreement among raft nodes before linearized reading' (duration: 456.020477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:23.508557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:23.051764Z","time spent":"456.787237ms","remote":"127.0.0.1:51172","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-18T22:49:34.305538Z","caller":"traceutil/trace.go:171","msg":"trace[219296715] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"159.611139ms","start":"2026-02-18T22:49:34.145901Z","end":"2026-02-18T22:49:34.305512Z","steps":["trace[219296715] 'process raft request' (duration: 153.649513ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:34.523999Z","caller":"traceutil/trace.go:171","msg":"trace[957564984] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"376.451717ms","start":"2026-02-18T22:49:34.146577Z","end":"2026-02-18T22:49:34.523029Z","steps":["trace[957564984] 'process raft request' (duration: 375.665428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:34.524124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:34.146571Z","time spent":"377.474572ms","remote":"127.0.0.1:51378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:49:34.527167Z","caller":"traceutil/trace.go:171","msg":"trace[768283193] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"206.451034ms","start":"2026-02-18T22:49:34.3207Z","end":"2026-02-18T22:49:34.527151Z","steps":["trace[768283193] 'process raft request' (duration: 206.305301ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:34.527322Z","caller":"traceutil/trace.go:171","msg":"trace[1326023711] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"206.560066ms","start":"2026-02-18T22:49:34.320757Z","end":"2026-02-18T22:49:34.527317Z","steps":["trace[1326023711] 'process raft request' (duration: 206.331891ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:34.527576Z","caller":"traceutil/trace.go:171","msg":"trace[1494468380] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"206.7ms","start":"2026-02-18T22:49:34.320867Z","end":"2026-02-18T22:49:34.527567Z","steps":["trace[1494468380] 'process raft request' (duration: 206.25129ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:34.528174Z","caller":"traceutil/trace.go:171","msg":"trace[1717965072] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:731; }","duration":"128.811957ms","start":"2026-02-18T22:49:34.399355Z","end":"2026-02-18T22:49:34.528167Z","steps":["trace[1717965072] 'read index received' (duration: 122.856502ms)","trace[1717965072] 'applied index is now lower than readState.Index' (duration: 5.954845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:49:34.528341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.981601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:49:34.528374Z","caller":"traceutil/trace.go:171","msg":"trace[970664029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"129.040622ms","start":"2026-02-18T22:49:34.399328Z","end":"2026-02-18T22:49:34.528369Z","steps":["trace[970664029] 'agreement among raft nodes before linearized reading' (duration: 128.877988ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:34.528639Z","caller":"traceutil/trace.go:171","msg":"trace[145494844] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"207.631413ms","start":"2026-02-18T22:49:34.321001Z","end":"2026-02-18T22:49:34.528632Z","steps":["trace[145494844] 'process raft request' (duration: 206.460695ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:34.528857Z","caller":"traceutil/trace.go:171","msg":"trace[803965889] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"207.700894ms","start":"2026-02-18T22:49:34.32115Z","end":"2026-02-18T22:49:34.528851Z","steps":["trace[803965889] 'process raft request' (duration: 206.983867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:56.246577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.595775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:49:56.246646Z","caller":"traceutil/trace.go:171","msg":"trace[457200579] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:840; }","duration":"324.705237ms","start":"2026-02-18T22:49:55.921927Z","end":"2026-02-18T22:49:56.246633Z","steps":["trace[457200579] 'count revisions from in-memory index tree' (duration: 324.535234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:56.246679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:55.921916Z","time spent":"324.754939ms","remote":"127.0.0.1:51272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:49:56.246721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.015464ms","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-18T22:49:56.246746Z","caller":"traceutil/trace.go:171","msg":"trace[1679071487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:840; }","duration":"324.041194ms","start":"2026-02-18T22:49:55.922698Z","end":"2026-02-18T22:49:56.246739Z","steps":["trace[1679071487] 'range keys from in-memory index tree' (duration: 323.905122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:56.246762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:55.922678Z","time spent":"324.079936ms","remote":"127.0.0.1:51172","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-18T22:49:56.246847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.012657ms","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-18T22:49:56.246868Z","caller":"traceutil/trace.go:171","msg":"trace[2107066867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"353.068339ms","start":"2026-02-18T22:49:55.893794Z","end":"2026-02-18T22:49:56.246862Z","steps":["trace[2107066867] 'range keys from in-memory index tree' (duration: 352.868495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:56.246886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:55.893774Z","time spent":"353.106539ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:49:56.247055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.205168ms","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-18T22:49:56.247076Z","caller":"traceutil/trace.go:171","msg":"trace[265370895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:840; }","duration":"326.26353ms","start":"2026-02-18T22:49:55.920806Z","end":"2026-02-18T22:49:56.24707Z","steps":["trace[265370895] 'range keys from in-memory index tree' (duration: 326.135217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:56.247097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:55.920793Z","time spent":"326.29827ms","remote":"127.0.0.1:51172","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-18T22:49:56.247139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.453432ms","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-18T22:49:56.247161Z","caller":"traceutil/trace.go:171","msg":"trace[1625200667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:840; }","duration":"325.484313ms","start":"2026-02-18T22:49:55.921669Z","end":"2026-02-18T22:49:56.247153Z","steps":["trace[1625200667] 'range keys from in-memory index tree' (duration: 325.408562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:49:56.247179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:49:55.921665Z","time spent":"325.509104ms","remote":"127.0.0.1:51172","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-18T22:49:56.361055Z","caller":"traceutil/trace.go:171","msg":"trace[830987942] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"106.542937ms","start":"2026-02-18T22:49:56.25448Z","end":"2026-02-18T22:49:56.361023Z","steps":["trace[830987942] 'process raft request' (duration: 105.805772ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:56.361102Z","caller":"traceutil/trace.go:171","msg":"trace[2026524366] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"109.964017ms","start":"2026-02-18T22:49:56.251126Z","end":"2026-02-18T22:49:56.36109Z","steps":["trace[2026524366] 'process raft request' (duration: 109.040948ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:49:56.361067Z","caller":"traceutil/trace.go:171","msg":"trace[593170907] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"107.426716ms","start":"2026-02-18T22:49:56.253629Z","end":"2026-02-18T22:49:56.361056Z","steps":["trace[593170907] 'process raft request' (duration: 106.63389ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:04.701385Z","caller":"traceutil/trace.go:171","msg":"trace[1752827271] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:891; }","duration":"275.51888ms","start":"2026-02-18T22:50:04.425849Z","end":"2026-02-18T22:50:04.701368Z","steps":["trace[1752827271] 'read index received' (duration: 275.392128ms)","trace[1752827271] 'applied index is now lower than readState.Index' (duration: 126.262µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:04.701516Z","caller":"traceutil/trace.go:171","msg":"trace[1171385351] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"292.528558ms","start":"2026-02-18T22:50:04.408981Z","end":"2026-02-18T22:50:04.70151Z","steps":["trace[1171385351] 'process raft request' (duration: 292.295303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:04.701681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.816655ms","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-18T22:50:04.701699Z","caller":"traceutil/trace.go:171","msg":"trace[250347830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:864; }","duration":"275.866316ms","start":"2026-02-18T22:50:04.425827Z","end":"2026-02-18T22:50:04.701694Z","steps":["trace[250347830] 'agreement among raft nodes before linearized reading' (duration: 275.769464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:04.70177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.568629ms","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-18T22:50:04.70178Z","caller":"traceutil/trace.go:171","msg":"trace[1550749343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:864; }","duration":"268.59346ms","start":"2026-02-18T22:50:04.433184Z","end":"2026-02-18T22:50:04.701777Z","steps":["trace[1550749343] 'agreement among raft nodes before linearized reading' (duration: 268.557809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:04.701845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.87195ms","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-18T22:50:04.701856Z","caller":"traceutil/trace.go:171","msg":"trace[1559047223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:864; }","duration":"233.914091ms","start":"2026-02-18T22:50:04.467938Z","end":"2026-02-18T22:50:04.701852Z","steps":["trace[1559047223] 'agreement among raft nodes before linearized reading' (duration: 233.88006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:04.934684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.700197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:50:04.93511Z","caller":"traceutil/trace.go:171","msg":"trace[1804741054] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"226.730228ms","start":"2026-02-18T22:50:04.708364Z","end":"2026-02-18T22:50:04.935095Z","steps":["trace[1804741054] 'process raft request' (duration: 226.681177ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:04.935891Z","caller":"traceutil/trace.go:171","msg":"trace[2124904015] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"228.781706ms","start":"2026-02-18T22:50:04.707097Z","end":"2026-02-18T22:50:04.935879Z","steps":["trace[2124904015] 'process raft request' (duration: 227.850618ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:04.936072Z","caller":"traceutil/trace.go:171","msg":"trace[810168826] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"229.422598ms","start":"2026-02-18T22:50:04.706639Z","end":"2026-02-18T22:50:04.936062Z","steps":["trace[810168826] 'compare' (duration: 225.194858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:05.896298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.274446ms","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-18T22:50:05.89638Z","caller":"traceutil/trace.go:171","msg":"trace[711547914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:868; }","duration":"109.393618ms","start":"2026-02-18T22:50:05.786972Z","end":"2026-02-18T22:50:05.896366Z","steps":["trace[711547914] 'range keys from in-memory index tree' (duration: 109.137184ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:06.91873Z","caller":"traceutil/trace.go:171","msg":"trace[1710764896] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"209.155117ms","start":"2026-02-18T22:50:06.709559Z","end":"2026-02-18T22:50:06.918714Z","steps":["trace[1710764896] 'process raft request' (duration: 209.042315ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:13.224348Z","caller":"traceutil/trace.go:171","msg":"trace[1666172031] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"162.857669ms","start":"2026-02-18T22:50:13.061438Z","end":"2026-02-18T22:50:13.224296Z","steps":["trace[1666172031] 'process raft request' (duration: 96.89965ms)","trace[1666172031] 'compare' (duration: 65.591153ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:13.226326Z","caller":"traceutil/trace.go:171","msg":"trace[485384538] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"164.240114ms","start":"2026-02-18T22:50:13.062072Z","end":"2026-02-18T22:50:13.226312Z","steps":["trace[485384538] 'process raft request' (duration: 162.176428ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:13.227467Z","caller":"traceutil/trace.go:171","msg":"trace[1964994473] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"164.226513ms","start":"2026-02-18T22:50:13.063227Z","end":"2026-02-18T22:50:13.227454Z","steps":["trace[1964994473] 'process raft request' (duration: 162.852669ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:15.363278Z","caller":"traceutil/trace.go:171","msg":"trace[1384876] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"133.30462ms","start":"2026-02-18T22:50:15.229956Z","end":"2026-02-18T22:50:15.363261Z","steps":["trace[1384876] 'read index received' (duration: 133.202528ms)","trace[1384876] 'applied index is now lower than readState.Index' (duration: 101.542µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:15.363358Z","caller":"traceutil/trace.go:171","msg":"trace[586843868] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"133.703017ms","start":"2026-02-18T22:50:15.229649Z","end":"2026-02-18T22:50:15.363352Z","steps":["trace[586843868] 'process raft request' (duration: 133.507604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:15.363498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.528373ms","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-18T22:50:15.363514Z","caller":"traceutil/trace.go:171","msg":"trace[579577614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"133.568375ms","start":"2026-02-18T22:50:15.229941Z","end":"2026-02-18T22:50:15.363509Z","steps":["trace[579577614] 'agreement among raft nodes before linearized reading' (duration: 133.488643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:15.363577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.568867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:50:15.363589Z","caller":"traceutil/trace.go:171","msg":"trace[1575386362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"132.592547ms","start":"2026-02-18T22:50:15.230993Z","end":"2026-02-18T22:50:15.363586Z","steps":["trace[1575386362] 'agreement among raft nodes before linearized reading' (duration: 132.557046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:15.363661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.939672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:50:15.363671Z","caller":"traceutil/trace.go:171","msg":"trace[339041577] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:894; }","duration":"122.970312ms","start":"2026-02-18T22:50:15.240698Z","end":"2026-02-18T22:50:15.363668Z","steps":["trace[339041577] 'agreement among raft nodes before linearized reading' (duration: 122.922672ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:17.540594Z","caller":"traceutil/trace.go:171","msg":"trace[491701801] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"111.551059ms","start":"2026-02-18T22:50:17.429024Z","end":"2026-02-18T22:50:17.540575Z","steps":["trace[491701801] 'process raft request' (duration: 111.452597ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:17.542262Z","caller":"traceutil/trace.go:171","msg":"trace[348348887] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"107.245366ms","start":"2026-02-18T22:50:17.434995Z","end":"2026-02-18T22:50:17.54224Z","steps":["trace[348348887] 'process raft request' (duration: 107.129214ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:17.857484Z","caller":"traceutil/trace.go:171","msg":"trace[946951623] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"206.152419ms","start":"2026-02-18T22:50:17.651307Z","end":"2026-02-18T22:50:17.857459Z","steps":["trace[946951623] 'process raft request' (duration: 130.112632ms)","trace[946951623] 'compare' (duration: 75.792413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:50:39.530518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.388108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:50:39.530799Z","caller":"traceutil/trace.go:171","msg":"trace[1639905283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"127.709743ms","start":"2026-02-18T22:50:39.40307Z","end":"2026-02-18T22:50:39.53078Z","steps":["trace[1639905283] 'range keys from in-memory index tree' (duration: 127.325728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:47.590506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.287168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:50:47.590613Z","caller":"traceutil/trace.go:171","msg":"trace[2053678610] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"192.106882ms","start":"2026-02-18T22:50:47.398497Z","end":"2026-02-18T22:50:47.590604Z","steps":["trace[2053678610] 'read index received' (duration: 15.06µs)","trace[2053678610] 'applied index is now lower than readState.Index' (duration: 192.090912ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:47.590678Z","caller":"traceutil/trace.go:171","msg":"trace[113885988] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"204.764739ms","start":"2026-02-18T22:50:47.385905Z","end":"2026-02-18T22:50:47.59067Z","steps":["trace[113885988] 'process raft request' (duration: 11.111556ms)","trace[113885988] 'compare' (duration: 193.185527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:50:47.590843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.342275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:50:47.590957Z","caller":"traceutil/trace.go:171","msg":"trace[1943230785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"192.480647ms","start":"2026-02-18T22:50:47.398471Z","end":"2026-02-18T22:50:47.590951Z","steps":["trace[1943230785] 'agreement among raft nodes before linearized reading' (duration: 192.333215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:48.383224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.447544ms","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-18T22:50:48.383292Z","caller":"traceutil/trace.go:171","msg":"trace[1043625164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"515.547055ms","start":"2026-02-18T22:50:47.867734Z","end":"2026-02-18T22:50:48.383281Z","steps":["trace[1043625164] 'range keys from in-memory index tree' (duration: 515.333712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:48.383322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:47.867719Z","time spent":"515.594985ms","remote":"127.0.0.1:51172","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-18T22:50:48.383562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.552993ms","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-18T22:50:48.383583Z","caller":"traceutil/trace.go:171","msg":"trace[293796498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"427.607493ms","start":"2026-02-18T22:50:47.955968Z","end":"2026-02-18T22:50:48.383575Z","steps":["trace[293796498] 'range keys from in-memory index tree' (duration: 427.477701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:48.3836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:50:47.955955Z","time spent":"427.641483ms","remote":"127.0.0.1:51172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:50:48.383787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.282502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:50:48.383806Z","caller":"traceutil/trace.go:171","msg":"trace[516164115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"147.328622ms","start":"2026-02-18T22:50:48.23647Z","end":"2026-02-18T22:50:48.383799Z","steps":["trace[516164115] 'range keys from in-memory index tree' (duration: 147.217751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:48.383877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.151456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:50:48.383892Z","caller":"traceutil/trace.go:171","msg":"trace[1266062415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"146.182567ms","start":"2026-02-18T22:50:48.237705Z","end":"2026-02-18T22:50:48.383887Z","steps":["trace[1266062415] 'range keys from in-memory index tree' (duration: 146.123806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:50:48.537636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.003128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:50:48.537787Z","caller":"traceutil/trace.go:171","msg":"trace[983389633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1024; }","duration":"139.16895ms","start":"2026-02-18T22:50:48.398605Z","end":"2026-02-18T22:50:48.537774Z","steps":["trace[983389633] 'agreement among raft nodes before linearized reading' (duration: 138.980827ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:50:48.53814Z","caller":"traceutil/trace.go:171","msg":"trace[211876253] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"147.725758ms","start":"2026-02-18T22:50:48.390404Z","end":"2026-02-18T22:50:48.53813Z","steps":["trace[211876253] 'process raft request' (duration: 95.540901ms)","trace[211876253] 'compare' (duration: 51.460947ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:50:48.538322Z","caller":"traceutil/trace.go:171","msg":"trace[1293048824] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"146.835206ms","start":"2026-02-18T22:50:48.391479Z","end":"2026-02-18T22:50:48.538314Z","steps":["trace[1293048824] 'process raft request' (duration: 146.059586ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:51:16.071777Z","caller":"traceutil/trace.go:171","msg":"trace[802841438] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"370.819604ms","start":"2026-02-18T22:51:15.700938Z","end":"2026-02-18T22:51:16.071757Z","steps":["trace[802841438] 'read index received' (duration: 370.677912ms)","trace[802841438] 'applied index is now lower than readState.Index' (duration: 141.052µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:51:16.072225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.874964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-02-18T22:51:16.072363Z","caller":"traceutil/trace.go:171","msg":"trace[775155698] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:1; response_revision:1103; }","duration":"322.988776ms","start":"2026-02-18T22:51:15.74929Z","end":"2026-02-18T22:51:16.072279Z","steps":["trace[775155698] 'agreement among raft nodes before linearized reading' (duration: 322.865745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:16.072446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:15.749269Z","time spent":"323.165998ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":224,"request content":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" "} {"level":"warn","ts":"2026-02-18T22:51:16.072804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.28524ms","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-18T22:51:16.073532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.583676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:88 size:64866"} {"level":"info","ts":"2026-02-18T22:51:16.072853Z","caller":"traceutil/trace.go:171","msg":"trace[555626889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"127.363792ms","start":"2026-02-18T22:51:15.945479Z","end":"2026-02-18T22:51:16.072843Z","steps":["trace[555626889] 'agreement among raft nodes before linearized reading' (duration: 127.237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:16.073878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.151305ms","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-18T22:51:16.074198Z","caller":"traceutil/trace.go:171","msg":"trace[599119567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"134.50715ms","start":"2026-02-18T22:51:15.939682Z","end":"2026-02-18T22:51:16.074189Z","steps":["trace[599119567] 'agreement among raft nodes before linearized reading' (duration: 134.121125ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:51:16.073579Z","caller":"traceutil/trace.go:171","msg":"trace[1284216138] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:88; response_revision:1103; }","duration":"372.663496ms","start":"2026-02-18T22:51:15.700906Z","end":"2026-02-18T22:51:16.073569Z","steps":["trace[1284216138] 'agreement among raft nodes before linearized reading' (duration: 371.982337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:16.074549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:15.700894Z","time spent":"373.592667ms","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":88,"response size":64889,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"info","ts":"2026-02-18T22:51:16.074098Z","caller":"traceutil/trace.go:171","msg":"trace[1308729163] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"396.566931ms","start":"2026-02-18T22:51:15.677448Z","end":"2026-02-18T22:51:16.074015Z","steps":["trace[1308729163] 'process raft request' (duration: 394.207722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:16.075612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:15.677432Z","time spent":"397.926626ms","remote":"127.0.0.1:51172","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-18T22:51:16.413733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.459398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/default\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-18T22:51:16.413803Z","caller":"traceutil/trace.go:171","msg":"trace[1719313254] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/default; range_end:; response_count:1; response_revision:1105; }","duration":"162.56332ms","start":"2026-02-18T22:51:16.251223Z","end":"2026-02-18T22:51:16.413786Z","steps":["trace[1719313254] 'range keys from in-memory index tree' (duration: 162.313166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:16.414049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.711442ms","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-18T22:51:16.414089Z","caller":"traceutil/trace.go:171","msg":"trace[951820585] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:1105; }","duration":"109.790364ms","start":"2026-02-18T22:51:16.304287Z","end":"2026-02-18T22:51:16.414077Z","steps":["trace[951820585] 'range keys from in-memory index tree' (duration: 109.666982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:17.336884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.146441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-dns\" ","response":"range_response_count:1 size:1211"} {"level":"info","ts":"2026-02-18T22:51:17.336972Z","caller":"traceutil/trace.go:171","msg":"trace[1707943291] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-dns; range_end:; response_count:1; response_revision:1108; }","duration":"448.269202ms","start":"2026-02-18T22:51:16.888682Z","end":"2026-02-18T22:51:17.336951Z","steps":["trace[1707943291] 'range keys from in-memory index tree' (duration: 448.039089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:17.337024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:16.888665Z","time spent":"448.345374ms","remote":"127.0.0.1:51104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1234,"request content":"key:\"/registry/services/specs/kube-system/kube-dns\" "} {"level":"warn","ts":"2026-02-18T22:51:17.33729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.039004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-18T22:51:17.337366Z","caller":"traceutil/trace.go:171","msg":"trace[760036566] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:1108; }","duration":"432.113595ms","start":"2026-02-18T22:51:16.905208Z","end":"2026-02-18T22:51:17.337321Z","steps":["trace[760036566] 'range keys from in-memory index tree' (duration: 431.988863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:17.33741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:16.905191Z","time spent":"432.206456ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-18T22:51:18.715075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.712232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-18T22:51:18.715429Z","caller":"traceutil/trace.go:171","msg":"trace[926031960] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:1110; }","duration":"436.107028ms","start":"2026-02-18T22:51:18.279308Z","end":"2026-02-18T22:51:18.715415Z","steps":["trace[926031960] 'range keys from in-memory index tree' (duration: 435.629102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:18.71557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:18.279294Z","time spent":"436.262409ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":228,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-02-18T22:51:18.716171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.474361ms","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-18T22:51:18.716651Z","caller":"traceutil/trace.go:171","msg":"trace[1293968352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1110; }","duration":"105.585293ms","start":"2026-02-18T22:51:18.610658Z","end":"2026-02-18T22:51:18.716244Z","steps":["trace[1293968352] 'range keys from in-memory index tree' (duration: 105.372451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:18.717059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.555565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:51:18.717116Z","caller":"traceutil/trace.go:171","msg":"trace[1046064326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1110; }","duration":"316.672567ms","start":"2026-02-18T22:51:18.400435Z","end":"2026-02-18T22:51:18.717108Z","steps":["trace[1046064326] 'range keys from in-memory index tree' (duration: 316.480714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:18.717192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:18.400422Z","time spent":"316.762717ms","remote":"127.0.0.1:50912","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-18T22:51:18.717906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.818176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:88 size:64866"} {"level":"info","ts":"2026-02-18T22:51:18.718027Z","caller":"traceutil/trace.go:171","msg":"trace[858532941] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:88; response_revision:1110; }","duration":"436.874526ms","start":"2026-02-18T22:51:18.281069Z","end":"2026-02-18T22:51:18.717944Z","steps":["trace[858532941] 'range keys from in-memory index tree' (duration: 435.900163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:18.718104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:51:18.281064Z","time spent":"437.022788ms","remote":"127.0.0.1:50992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":88,"response size":64889,"request content":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 "} {"level":"info","ts":"2026-02-18T22:51:18.920969Z","caller":"traceutil/trace.go:171","msg":"trace[2045721169] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"111.813308ms","start":"2026-02-18T22:51:18.809132Z","end":"2026-02-18T22:51:18.920945Z","steps":["trace[2045721169] 'read index received' (duration: 74.284323ms)","trace[2045721169] 'applied index is now lower than readState.Index' (duration: 37.527945ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:51:18.921409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.267434ms","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-18T22:51:18.921813Z","caller":"traceutil/trace.go:171","msg":"trace[257097765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"112.521836ms","start":"2026-02-18T22:51:18.809095Z","end":"2026-02-18T22:51:18.921617Z","steps":["trace[257097765] 'agreement among raft nodes before linearized reading' (duration: 112.164382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:51:18.922208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.063544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-18T22:51:18.922439Z","caller":"traceutil/trace.go:171","msg":"trace[866184404] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:1112; }","duration":"100.294368ms","start":"2026-02-18T22:51:18.822132Z","end":"2026-02-18T22:51:18.922427Z","steps":["trace[866184404] 'agreement among raft nodes before linearized reading' (duration: 99.983533ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:51:18.921486Z","caller":"traceutil/trace.go:171","msg":"trace[1105628492] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"133.629193ms","start":"2026-02-18T22:51:18.787837Z","end":"2026-02-18T22:51:18.921466Z","steps":["trace[1105628492] 'process raft request' (duration: 95.650622ms)","trace[1105628492] 'compare' (duration: 37.351993ms)"],"step_count":2}