{"level":"warn","ts":"2026-02-18T22:53:17.725504Z","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:53:17.727089Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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:53:17.727237Z","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:53:17.727249Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-18T22:53:17.727266Z","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:53:17.728677Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-02-18T22:53:17.728835Z","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://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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:53:17.737401Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.204671ms"} {"level":"info","ts":"2026-02-18T22:53:17.761608Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-02-18T22:53:17.761724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:53:17.76176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-02-18T22:53:17.76182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:53:17.761856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-02-18T22:53:17.761941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-02-18T22:53:17.772102Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:53:17.773444Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:53:17.774615Z","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:53:17.777368Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:53:17.778258Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:53:17.778375Z","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:53:17.778527Z","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:53:17.778546Z","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:53:17.78269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-02-18T22:53:17.782757Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-02-18T22:53:17.787401Z","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:53:17.787673Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-18T22:53:17.787784Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-02-18T22:53:17.790775Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:53:17.790798Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:53:18.262795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:53:18.26289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:53:18.262921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-02-18T22:53:18.262961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:53:18.262984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-18T22:53:18.263004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-02-18T22:53:18.263034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-02-18T22:53:18.339494Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:18.353354Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:53:18.353431Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:53:18.353811Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:53:18.355353Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-02-18T22:53:18.357037Z","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:53:18.361012Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:53:18.362682Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T22:53:18.381192Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:18.381263Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:18.381873Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-18T22:53:24.556933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.588245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:24.557035Z","caller":"traceutil/trace.go:171","msg":"trace[303948819] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"187.603461ms","start":"2026-02-18T22:53:24.369416Z","end":"2026-02-18T22:53:24.55702Z","steps":["trace[303948819] 'read index received' (duration: 51.400821ms)","trace[303948819] 'applied index is now lower than readState.Index' (duration: 136.2013ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:24.557334Z","caller":"traceutil/trace.go:171","msg":"trace[1710776070] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"197.211634ms","start":"2026-02-18T22:53:24.360024Z","end":"2026-02-18T22:53:24.557235Z","steps":["trace[1710776070] 'process raft request' (duration: 60.81879ms)","trace[1710776070] 'compare' (duration: 134.453559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:24.558043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.612315ms","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:53:24.558086Z","caller":"traceutil/trace.go:171","msg":"trace[1329309563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:281; }","duration":"188.684306ms","start":"2026-02-18T22:53:24.369386Z","end":"2026-02-18T22:53:24.55807Z","steps":["trace[1329309563] 'agreement among raft nodes before linearized reading' (duration: 188.540693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:24.558318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.462614ms","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:53:24.558354Z","caller":"traceutil/trace.go:171","msg":"trace[687874787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:281; }","duration":"180.556087ms","start":"2026-02-18T22:53:24.377787Z","end":"2026-02-18T22:53:24.558343Z","steps":["trace[687874787] 'agreement among raft nodes before linearized reading' (duration: 180.442344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:24.558753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.161554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:24.558793Z","caller":"traceutil/trace.go:171","msg":"trace[1458817546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:281; }","duration":"179.211385ms","start":"2026-02-18T22:53:24.379567Z","end":"2026-02-18T22:53:24.558779Z","steps":["trace[1458817546] 'agreement among raft nodes before linearized reading' (duration: 179.152803ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:24.780763Z","caller":"traceutil/trace.go:171","msg":"trace[33418115] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"168.708439ms","start":"2026-02-18T22:53:24.612007Z","end":"2026-02-18T22:53:24.780716Z","steps":["trace[33418115] 'process raft request' (duration: 168.652048ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:24.78079Z","caller":"traceutil/trace.go:171","msg":"trace[1747763446] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"194.291356ms","start":"2026-02-18T22:53:24.586476Z","end":"2026-02-18T22:53:24.780767Z","steps":["trace[1747763446] 'process raft request' (duration: 124.425125ms)","trace[1747763446] 'compare' (duration: 69.662247ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:24.780932Z","caller":"traceutil/trace.go:171","msg":"trace[151216601] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"171.73721ms","start":"2026-02-18T22:53:24.609187Z","end":"2026-02-18T22:53:24.780924Z","steps":["trace[151216601] 'read index received' (duration: 101.722555ms)","trace[151216601] 'applied index is now lower than readState.Index' (duration: 70.013945ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:24.78109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.257638ms","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:53:24.781123Z","caller":"traceutil/trace.go:171","msg":"trace[117649899] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:286; }","duration":"195.309259ms","start":"2026-02-18T22:53:24.585805Z","end":"2026-02-18T22:53:24.781114Z","steps":["trace[117649899] 'agreement among raft nodes before linearized reading' (duration: 195.250368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:24.781246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.862361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"info","ts":"2026-02-18T22:53:24.781272Z","caller":"traceutil/trace.go:171","msg":"trace[2035867066] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:286; }","duration":"123.908783ms","start":"2026-02-18T22:53:24.65735Z","end":"2026-02-18T22:53:24.781258Z","steps":["trace[2035867066] 'agreement among raft nodes before linearized reading' (duration: 123.868162ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:25.068384Z","caller":"traceutil/trace.go:171","msg":"trace[519033136] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"159.600257ms","start":"2026-02-18T22:53:24.908765Z","end":"2026-02-18T22:53:25.068366Z","steps":["trace[519033136] 'read index received' (duration: 78.35767ms)","trace[519033136] 'applied index is now lower than readState.Index' (duration: 81.241877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:25.068477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.691379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:25.068499Z","caller":"traceutil/trace.go:171","msg":"trace[891911610] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:293; }","duration":"159.72952ms","start":"2026-02-18T22:53:24.908762Z","end":"2026-02-18T22:53:25.068492Z","steps":["trace[891911610] 'agreement among raft nodes before linearized reading' (duration: 159.668728ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:25.06868Z","caller":"traceutil/trace.go:171","msg":"trace[563931752] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"171.519015ms","start":"2026-02-18T22:53:24.897112Z","end":"2026-02-18T22:53:25.068631Z","steps":["trace[563931752] 'process raft request' (duration: 89.979301ms)","trace[563931752] 'compare' (duration: 81.194396ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:27.768134Z","caller":"traceutil/trace.go:171","msg":"trace[27180455] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"112.414254ms","start":"2026-02-18T22:53:27.655699Z","end":"2026-02-18T22:53:27.768114Z","steps":["trace[27180455] 'process raft request' (duration: 102.683554ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:27.76924Z","caller":"traceutil/trace.go:171","msg":"trace[1241377455] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"109.391063ms","start":"2026-02-18T22:53:27.659833Z","end":"2026-02-18T22:53:27.769224Z","steps":["trace[1241377455] 'process raft request' (duration: 109.367632ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:27.769401Z","caller":"traceutil/trace.go:171","msg":"trace[445600910] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"112.70083ms","start":"2026-02-18T22:53:27.656693Z","end":"2026-02-18T22:53:27.769394Z","steps":["trace[445600910] 'process raft request' (duration: 112.401773ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:27.769549Z","caller":"traceutil/trace.go:171","msg":"trace[375753042] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"110.320214ms","start":"2026-02-18T22:53:27.659223Z","end":"2026-02-18T22:53:27.769543Z","steps":["trace[375753042] 'process raft request' (duration: 109.946555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:31.97534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.355541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:31.975434Z","caller":"traceutil/trace.go:171","msg":"trace[861994282] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"179.45646ms","start":"2026-02-18T22:53:31.795965Z","end":"2026-02-18T22:53:31.975421Z","steps":["trace[861994282] 'process raft request' (duration: 32.786031ms)","trace[861994282] 'compare' (duration: 146.238449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:33.136276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.084316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"warn","ts":"2026-02-18T22:53:33.136297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.546435ms","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:53:33.136336Z","caller":"traceutil/trace.go:171","msg":"trace[762459422] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:371; }","duration":"212.191569ms","start":"2026-02-18T22:53:32.92413Z","end":"2026-02-18T22:53:33.136322Z","steps":["trace[762459422] 'range keys from in-memory index tree' (duration: 211.966174ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:33.13636Z","caller":"traceutil/trace.go:171","msg":"trace[952853478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"294.658888ms","start":"2026-02-18T22:53:32.841686Z","end":"2026-02-18T22:53:33.136345Z","steps":["trace[952853478] 'range keys from in-memory index tree' (duration: 294.463493ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:33.584612Z","caller":"traceutil/trace.go:171","msg":"trace[234784276] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"441.572692ms","start":"2026-02-18T22:53:33.142998Z","end":"2026-02-18T22:53:33.584571Z","steps":["trace[234784276] 'process raft request' (duration: 441.400888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:33.584854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:33.14298Z","time spent":"441.779407ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:35.49275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000388022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T22:53:35.492868Z","caller":"traceutil/trace.go:171","msg":"trace[191487007] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000569116s","start":"2026-02-18T22:53:33.492277Z","end":"2026-02-18T22:53:35.492846Z","steps":["trace[191487007] 'agreement among raft nodes before linearized reading' (duration: 2.000423002s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:35.492931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:33.49223Z","time spent":"2.000691589s","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:53:36.0775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.734089ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:36.077623Z","caller":"traceutil/trace.go:171","msg":"trace[870776580] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:372; }","duration":"288.868122ms","start":"2026-02-18T22:53:35.788735Z","end":"2026-02-18T22:53:36.077603Z","steps":["trace[870776580] 'range keys from in-memory index tree' (duration: 288.710908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:36.165953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.062789418s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:53:36.166697Z","caller":"traceutil/trace.go:171","msg":"trace[230239013] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:379; }","duration":"2.674325068s","start":"2026-02-18T22:53:33.492331Z","end":"2026-02-18T22:53:36.166656Z","steps":["trace[230239013] 'read index received' (duration: 92.036644ms)","trace[230239013] 'applied index is now lower than readState.Index' (duration: 2.582287534s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:36.167004Z","caller":"traceutil/trace.go:171","msg":"trace[1629081232] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"3.023778593s","start":"2026-02-18T22:53:33.143211Z","end":"2026-02-18T22:53:36.16699Z","steps":["trace[1629081232] 'process raft request' (duration: 3.023168578s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:36.167121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:33.143205Z","time spent":"3.023832185s","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6883,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:53:36.175177Z","caller":"traceutil/trace.go:171","msg":"trace[591666741] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"2.385472666s","start":"2026-02-18T22:53:33.789689Z","end":"2026-02-18T22:53:36.175162Z","steps":["trace[591666741] 'process raft request' (duration: 2.385349223s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:36.175351Z","caller":"traceutil/trace.go:171","msg":"trace[548376873] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"2.227885302s","start":"2026-02-18T22:53:33.947454Z","end":"2026-02-18T22:53:36.175339Z","steps":["trace[548376873] 'process raft request' (duration: 2.227671277s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:36.175398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:33.789672Z","time spent":"2.385554248s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:36.175423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:33.947421Z","time spent":"2.227960463s","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3999,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:53:36.175766Z","caller":"traceutil/trace.go:171","msg":"trace[1810623277] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"678.40039ms","start":"2026-02-18T22:53:35.497356Z","end":"2026-02-18T22:53:36.175757Z","steps":["trace[1810623277] 'process raft request' (duration: 677.934769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:36.175865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:35.497326Z","time spent":"678.465731ms","remote":"127.0.0.1:36274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:53:36.182266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836015337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-18T22:53:36.182289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.159627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"warn","ts":"2026-02-18T22:53:36.182345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449162301s","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:53:36.182351Z","caller":"traceutil/trace.go:171","msg":"trace[1819336916] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:376; }","duration":"827.26442ms","start":"2026-02-18T22:53:35.355074Z","end":"2026-02-18T22:53:36.182338Z","steps":["trace[1819336916] 'agreement among raft nodes before linearized reading' (duration: 821.619077ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:36.182366Z","caller":"traceutil/trace.go:171","msg":"trace[744640397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:376; }","duration":"1.449218272s","start":"2026-02-18T22:53:34.733142Z","end":"2026-02-18T22:53:36.18236Z","steps":["trace[744640397] 'agreement among raft nodes before linearized reading' (duration: 1.4435829s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:36.182383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:35.355056Z","time spent":"827.321122ms","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4035,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T22:53:36.182402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:34.733122Z","time spent":"1.449274484s","remote":"127.0.0.1:36454","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:53:36.182655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510914073s","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:53:36.182682Z","caller":"traceutil/trace.go:171","msg":"trace[1138640940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"1.510977045s","start":"2026-02-18T22:53:34.671696Z","end":"2026-02-18T22:53:36.182673Z","steps":["trace[1138640940] 'agreement among raft nodes before linearized reading' (duration: 1.504980234s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:36.182702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:34.671676Z","time spent":"1.511021556s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:53:36.182794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.686471ms","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:53:36.182811Z","caller":"traceutil/trace.go:171","msg":"trace[176191966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:376; }","duration":"594.728573ms","start":"2026-02-18T22:53:35.588077Z","end":"2026-02-18T22:53:36.182805Z","steps":["trace[176191966] 'agreement among raft nodes before linearized reading' (duration: 588.610539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:36.182829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:35.588062Z","time spent":"594.761783ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T22:53:36.182317Z","caller":"traceutil/trace.go:171","msg":"trace[2048708801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"1.836095299s","start":"2026-02-18T22:53:34.346197Z","end":"2026-02-18T22:53:36.182293Z","steps":["trace[2048708801] 'agreement among raft nodes before linearized reading' (duration: 1.830550259s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:36.182931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:34.346178Z","time spent":"1.836745303s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T22:53:51.71384Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338819128706577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:53:52.214332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338819128706577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:53:52.28704Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.212301153s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:53:52.287289Z","caller":"traceutil/trace.go:171","msg":"trace[915685664] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"1.07400481s","start":"2026-02-18T22:53:51.213269Z","end":"2026-02-18T22:53:52.287274Z","steps":["trace[915685664] 'read index received' (duration: 1.073846117s)","trace[915685664] 'applied index is now lower than readState.Index' (duration: 158.043µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:52.287457Z","caller":"traceutil/trace.go:171","msg":"trace[983108270] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.212794314s","start":"2026-02-18T22:53:51.074654Z","end":"2026-02-18T22:53:52.287448Z","steps":["trace[983108270] 'process raft request' (duration: 1.212503818s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:52.287536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:51.074638Z","time spent":"1.212836335s","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:52.287661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074392489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:52.287683Z","caller":"traceutil/trace.go:171","msg":"trace[240131197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"1.074459282s","start":"2026-02-18T22:53:51.213216Z","end":"2026-02-18T22:53:52.287675Z","steps":["trace[240131197] 'agreement among raft nodes before linearized reading' (duration: 1.074427471s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:52.2877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:51.213194Z","time spent":"1.074502722s","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:53:52.287807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.229013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:53:52.287824Z","caller":"traceutil/trace.go:171","msg":"trace[1792587718] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:484; }","duration":"495.281914ms","start":"2026-02-18T22:53:51.792537Z","end":"2026-02-18T22:53:52.287819Z","steps":["trace[1792587718] 'agreement among raft nodes before linearized reading' (duration: 495.249453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:52.287839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:51.792519Z","time spent":"495.317024ms","remote":"127.0.0.1:36220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"warn","ts":"2026-02-18T22:53:59.950073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.912665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kc7m9\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-18T22:53:59.950129Z","caller":"traceutil/trace.go:171","msg":"trace[1999472488] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kc7m9; range_end:; response_count:1; response_revision:505; }","duration":"196.006429ms","start":"2026-02-18T22:53:59.754108Z","end":"2026-02-18T22:53:59.950115Z","steps":["trace[1999472488] 'range keys from in-memory index tree' (duration: 195.521046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:00.713975Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338819128706635,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:00.743459Z","caller":"traceutil/trace.go:171","msg":"trace[686148065] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"530.395591ms","start":"2026-02-18T22:54:00.213033Z","end":"2026-02-18T22:54:00.743429Z","steps":["trace[686148065] 'read index received' (duration: 530.107255ms)","trace[686148065] 'applied index is now lower than readState.Index' (duration: 287.226µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:00.74396Z","caller":"traceutil/trace.go:171","msg":"trace[616228879] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"778.806971ms","start":"2026-02-18T22:53:59.965133Z","end":"2026-02-18T22:54:00.74394Z","steps":["trace[616228879] 'process raft request' (duration: 778.109185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:00.744119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:59.965115Z","time spent":"778.896673ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:00.74437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.328993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:00.744413Z","caller":"traceutil/trace.go:171","msg":"trace[831942380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"531.410405ms","start":"2026-02-18T22:54:00.212991Z","end":"2026-02-18T22:54:00.744402Z","steps":["trace[831942380] 'agreement among raft nodes before linearized reading' (duration: 531.343863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:00.744445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:00.212969Z","time spent":"531.469716ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:00.744868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.380681ms","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:54:00.74491Z","caller":"traceutil/trace.go:171","msg":"trace[1993725181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"391.458064ms","start":"2026-02-18T22:54:00.353441Z","end":"2026-02-18T22:54:00.744899Z","steps":["trace[1993725181] 'agreement among raft nodes before linearized reading' (duration: 391.168488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:00.744937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:00.353422Z","time spent":"391.508195ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T22:54:00.74528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.88281ms","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:54:00.745898Z","caller":"traceutil/trace.go:171","msg":"trace[1711867789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"314.516635ms","start":"2026-02-18T22:54:00.431371Z","end":"2026-02-18T22:54:00.745888Z","steps":["trace[1711867789] 'agreement among raft nodes before linearized reading' (duration: 313.832168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:00.745933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:00.431366Z","time spent":"314.555957ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:00.746172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.817543ms","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:54:00.746198Z","caller":"traceutil/trace.go:171","msg":"trace[1664820446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:506; }","duration":"314.882214ms","start":"2026-02-18T22:54:00.431308Z","end":"2026-02-18T22:54:00.74619Z","steps":["trace[1664820446] 'agreement among raft nodes before linearized reading' (duration: 314.795762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:00.746221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:00.431286Z","time spent":"314.929435ms","remote":"127.0.0.1:36454","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:54:00.746357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.91957ms","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:54:00.746379Z","caller":"traceutil/trace.go:171","msg":"trace[241892950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"378.962161ms","start":"2026-02-18T22:54:00.36741Z","end":"2026-02-18T22:54:00.746372Z","steps":["trace[241892950] 'agreement among raft nodes before linearized reading' (duration: 378.89764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:00.7464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:00.367399Z","time spent":"378.995492ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T22:54:02.020307Z","caller":"traceutil/trace.go:171","msg":"trace[1904595921] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"237.869532ms","start":"2026-02-18T22:54:01.782413Z","end":"2026-02-18T22:54:02.020283Z","steps":["trace[1904595921] 'process raft request' (duration: 237.664007ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:02.229292Z","caller":"traceutil/trace.go:171","msg":"trace[865370552] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:524; }","duration":"436.958674ms","start":"2026-02-18T22:54:01.792321Z","end":"2026-02-18T22:54:02.22928Z","steps":["trace[865370552] 'read index received' (duration: 227.704724ms)","trace[865370552] 'applied index is now lower than readState.Index' (duration: 209.25341ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:02.229432Z","caller":"traceutil/trace.go:171","msg":"trace[181019278] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"441.568251ms","start":"2026-02-18T22:54:01.787858Z","end":"2026-02-18T22:54:02.229426Z","steps":["trace[181019278] 'process raft request' (duration: 422.699807ms)","trace[181019278] 'compare' (duration: 18.463565ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:02.229491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:01.78785Z","time spent":"441.595402ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16901,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:02.229586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.262791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:54:02.229601Z","caller":"traceutil/trace.go:171","msg":"trace[1181282511] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:513; }","duration":"437.296431ms","start":"2026-02-18T22:54:01.792299Z","end":"2026-02-18T22:54:02.229596Z","steps":["trace[1181282511] 'agreement among raft nodes before linearized reading' (duration: 437.26849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:02.229613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:01.792295Z","time spent":"437.315731ms","remote":"127.0.0.1:36220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"info","ts":"2026-02-18T22:54:04.355773Z","caller":"traceutil/trace.go:171","msg":"trace[295662532] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"241.279423ms","start":"2026-02-18T22:54:04.114474Z","end":"2026-02-18T22:54:04.355754Z","steps":["trace[295662532] 'process raft request' (duration: 241.14006ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:04.356147Z","caller":"traceutil/trace.go:171","msg":"trace[375062457] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:555; }","duration":"144.206201ms","start":"2026-02-18T22:54:04.211925Z","end":"2026-02-18T22:54:04.356131Z","steps":["trace[375062457] 'read index received' (duration: 144.19979ms)","trace[375062457] 'applied index is now lower than readState.Index' (duration: 5.271µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:04.356289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.329833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:04.356326Z","caller":"traceutil/trace.go:171","msg":"trace[1232410219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"144.431205ms","start":"2026-02-18T22:54:04.211885Z","end":"2026-02-18T22:54:04.356317Z","steps":["trace[1232410219] 'agreement among raft nodes before linearized reading' (duration: 144.312542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.078217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.465853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:14.078438Z","caller":"traceutil/trace.go:171","msg":"trace[824353994] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"617.003456ms","start":"2026-02-18T22:54:13.461422Z","end":"2026-02-18T22:54:14.078425Z","steps":["trace[824353994] 'process raft request' (duration: 231.154074ms)","trace[824353994] 'compare' (duration: 385.196047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:14.079869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:13.461408Z","time spent":"618.437289ms","remote":"127.0.0.1:36666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:14.0805Z","caller":"traceutil/trace.go:171","msg":"trace[7030778] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"618.516762ms","start":"2026-02-18T22:54:13.461976Z","end":"2026-02-18T22:54:14.080493Z","steps":["trace[7030778] 'process raft request' (duration: 616.362781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.085301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:13.461969Z","time spent":"622.773471ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:14.086512Z","caller":"traceutil/trace.go:171","msg":"trace[122816470] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"622.944967ms","start":"2026-02-18T22:54:13.463554Z","end":"2026-02-18T22:54:14.086499Z","steps":["trace[122816470] 'process raft request' (duration: 616.787462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.086554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:13.463548Z","time spent":"622.985958ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:14.087047Z","caller":"traceutil/trace.go:171","msg":"trace[1142535128] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"622.914055ms","start":"2026-02-18T22:54:13.464125Z","end":"2026-02-18T22:54:14.087039Z","steps":["trace[1142535128] 'process raft request' (duration: 622.638359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:14.087106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:13.464119Z","time spent":"622.967187ms","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4438,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:15.492978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.690273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:15.493043Z","caller":"traceutil/trace.go:171","msg":"trace[392133820] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:643; }","duration":"2.003578436s","start":"2026-02-18T22:54:13.489454Z","end":"2026-02-18T22:54:15.493032Z","steps":["trace[392133820] 'read index received' (duration: 203.082954ms)","trace[392133820] 'applied index is now lower than readState.Index' (duration: 1.800494002s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:15.493083Z","caller":"traceutil/trace.go:171","msg":"trace[566403770] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"2.005183834s","start":"2026-02-18T22:54:13.487891Z","end":"2026-02-18T22:54:15.493075Z","steps":["trace[566403770] 'process raft request' (duration: 1.028347597s)","trace[566403770] 'compare' (duration: 976.437618ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:15.493107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:13.487865Z","time spent":"2.005230245s","remote":"127.0.0.1:36274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:54:15.493282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003823901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-7sg67\" ","response":"range_response_count:1 size:3813"} {"level":"info","ts":"2026-02-18T22:54:15.493297Z","caller":"traceutil/trace.go:171","msg":"trace[1424443093] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-7sg67; range_end:; response_count:1; response_revision:631; }","duration":"2.003856553s","start":"2026-02-18T22:54:13.489435Z","end":"2026-02-18T22:54:15.493291Z","steps":["trace[1424443093] 'agreement among raft nodes before linearized reading' (duration: 2.003766471s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.493311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:13.489429Z","time spent":"2.003879663s","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3835,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-7sg67\" "} {"level":"warn","ts":"2026-02-18T22:54:15.495036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.080275ms","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:54:15.49508Z","caller":"traceutil/trace.go:171","msg":"trace[1593269505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"673.155447ms","start":"2026-02-18T22:54:14.821914Z","end":"2026-02-18T22:54:15.495069Z","steps":["trace[1593269505] 'agreement among raft nodes before linearized reading' (duration: 673.063615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.495109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:14.821901Z","time spent":"673.204138ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:54:15.495212Z","caller":"traceutil/trace.go:171","msg":"trace[1323000396] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.399670388s","start":"2026-02-18T22:54:14.095535Z","end":"2026-02-18T22:54:15.495205Z","steps":["trace[1323000396] 'process raft request' (duration: 1.399204717s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.495266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:14.095521Z","time spent":"1.399703789s","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4438,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:15.495366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.641828ms","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:54:15.49538Z","caller":"traceutil/trace.go:171","msg":"trace[186475427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"576.693459ms","start":"2026-02-18T22:54:14.918682Z","end":"2026-02-18T22:54:15.495376Z","steps":["trace[186475427] 'agreement among raft nodes before linearized reading' (duration: 576.640738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.495392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:14.918672Z","time spent":"576.71706ms","remote":"127.0.0.1:36454","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:54:15.495467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.302818ms","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:54:15.495479Z","caller":"traceutil/trace.go:171","msg":"trace[1325894829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"587.33323ms","start":"2026-02-18T22:54:14.908142Z","end":"2026-02-18T22:54:15.495475Z","steps":["trace[1325894829] 'agreement among raft nodes before linearized reading' (duration: 587.300369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.495491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:14.908131Z","time spent":"587.35679ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:15.495566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.871344ms","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:54:15.495578Z","caller":"traceutil/trace.go:171","msg":"trace[1797946703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"599.923265ms","start":"2026-02-18T22:54:14.895651Z","end":"2026-02-18T22:54:15.495574Z","steps":["trace[1797946703] 'agreement among raft nodes before linearized reading' (duration: 599.888614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.495597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:14.895614Z","time spent":"599.972386ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:54:15.500003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225380659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-18T22:54:15.500025Z","caller":"traceutil/trace.go:171","msg":"trace[854858284] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:632; }","duration":"1.225434421s","start":"2026-02-18T22:54:14.274585Z","end":"2026-02-18T22:54:15.500019Z","steps":["trace[854858284] 'agreement among raft nodes before linearized reading' (duration: 1.225361309s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.50004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:14.274546Z","time spent":"1.225490702s","remote":"127.0.0.1:36412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":201,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-18T22:54:15.50013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225454971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-18T22:54:15.500145Z","caller":"traceutil/trace.go:171","msg":"trace[310242842] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:632; }","duration":"1.225484593s","start":"2026-02-18T22:54:14.274656Z","end":"2026-02-18T22:54:15.500141Z","steps":["trace[310242842] 'agreement among raft nodes before linearized reading' (duration: 1.225452932s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.500157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:14.27465Z","time spent":"1.225504343s","remote":"127.0.0.1:36412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":201,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-18T22:54:15.500349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287045859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:15.500363Z","caller":"traceutil/trace.go:171","msg":"trace[1144208633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"1.287108661s","start":"2026-02-18T22:54:14.213249Z","end":"2026-02-18T22:54:15.500358Z","steps":["trace[1144208633] 'agreement among raft nodes before linearized reading' (duration: 1.28708626s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.500374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:14.213233Z","time spent":"1.287139042s","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:15.500509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633914495s","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:54:15.500522Z","caller":"traceutil/trace.go:171","msg":"trace[451355347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:632; }","duration":"1.633952106s","start":"2026-02-18T22:54:13.866566Z","end":"2026-02-18T22:54:15.500518Z","steps":["trace[451355347] 'agreement among raft nodes before linearized reading' (duration: 1.633908024s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.500536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:13.866554Z","time spent":"1.633978728s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:15.494967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824312102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-18T22:54:15.503825Z","caller":"traceutil/trace.go:171","msg":"trace[915177468] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:632; }","duration":"1.833199152s","start":"2026-02-18T22:54:13.670615Z","end":"2026-02-18T22:54:15.503814Z","steps":["trace[915177468] 'agreement among raft nodes before linearized reading' (duration: 1.824314853s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:15.503864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:13.670601Z","time spent":"1.833240502s","remote":"127.0.0.1:36412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":201,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-18T22:54:16.005659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338819128706885,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:16.098745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.153272ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:16.098835Z","caller":"traceutil/trace.go:171","msg":"trace[1826897707] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:632; }","duration":"310.254565ms","start":"2026-02-18T22:54:15.78857Z","end":"2026-02-18T22:54:16.098825Z","steps":["trace[1826897707] 'range keys from in-memory index tree' (duration: 310.142212ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:16.099683Z","caller":"traceutil/trace.go:171","msg":"trace[730024066] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"595.103401ms","start":"2026-02-18T22:54:15.504563Z","end":"2026-02-18T22:54:16.099666Z","steps":["trace[730024066] 'process raft request' (duration: 594.992999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.099789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.50455Z","time spent":"595.182303ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4682,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:16.11268Z","caller":"traceutil/trace.go:171","msg":"trace[1882182371] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"602.614368ms","start":"2026-02-18T22:54:15.510047Z","end":"2026-02-18T22:54:16.112662Z","steps":["trace[1882182371] 'process raft request' (duration: 602.555757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.112766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.510039Z","time spent":"602.679789ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:16.112832Z","caller":"traceutil/trace.go:171","msg":"trace[306875077] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"604.404941ms","start":"2026-02-18T22:54:15.508423Z","end":"2026-02-18T22:54:16.112828Z","steps":["trace[306875077] 'process raft request' (duration: 604.130965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.112854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.508391Z","time spent":"604.450082ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:16.112919Z","caller":"traceutil/trace.go:171","msg":"trace[283932183] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"604.438982ms","start":"2026-02-18T22:54:15.508466Z","end":"2026-02-18T22:54:16.112905Z","steps":["trace[283932183] 'process raft request' (duration: 604.119164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.112942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.508462Z","time spent":"604.466332ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:16.112955Z","caller":"traceutil/trace.go:171","msg":"trace[430320448] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"606.029558ms","start":"2026-02-18T22:54:15.506906Z","end":"2026-02-18T22:54:16.112935Z","steps":["trace[430320448] 'process raft request' (duration: 605.569058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.113043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.506895Z","time spent":"606.10469ms","remote":"127.0.0.1:36274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:54:16.113112Z","caller":"traceutil/trace.go:171","msg":"trace[553361064] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"602.989506ms","start":"2026-02-18T22:54:15.510117Z","end":"2026-02-18T22:54:16.113107Z","steps":["trace[553361064] 'process raft request' (duration: 602.499915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.113141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.510112Z","time spent":"603.014698ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:16.113223Z","caller":"traceutil/trace.go:171","msg":"trace[1946789841] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:650; }","duration":"607.688068ms","start":"2026-02-18T22:54:15.50553Z","end":"2026-02-18T22:54:16.113218Z","steps":["trace[1946789841] 'read index received' (duration: 594.405855ms)","trace[1946789841] 'applied index is now lower than readState.Index' (duration: 13.281603ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:16.113287Z","caller":"traceutil/trace.go:171","msg":"trace[518166008] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"601.505641ms","start":"2026-02-18T22:54:15.511777Z","end":"2026-02-18T22:54:16.113282Z","steps":["trace[518166008] 'process raft request' (duration: 601.304857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.113309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.511768Z","time spent":"601.527372ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:16.113617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.863432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-wgdt2\" ","response":"range_response_count:1 size:3849"} {"level":"info","ts":"2026-02-18T22:54:16.113643Z","caller":"traceutil/trace.go:171","msg":"trace[2134493666] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-wgdt2; range_end:; response_count:1; response_revision:639; }","duration":"607.907503ms","start":"2026-02-18T22:54:15.505728Z","end":"2026-02-18T22:54:16.113636Z","steps":["trace[2134493666] 'agreement among raft nodes before linearized reading' (duration: 607.81483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.113663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.505722Z","time spent":"607.936753ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3871,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-wgdt2\" "} {"level":"warn","ts":"2026-02-18T22:54:16.113759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.22915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:16.113776Z","caller":"traceutil/trace.go:171","msg":"trace[1448398332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"608.263741ms","start":"2026-02-18T22:54:15.505507Z","end":"2026-02-18T22:54:16.11377Z","steps":["trace[1448398332] 'agreement among raft nodes before linearized reading' (duration: 608.23586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.113793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.505495Z","time spent":"608.294592ms","remote":"127.0.0.1:36194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:16.113968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.133598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-7sg67\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-18T22:54:16.11399Z","caller":"traceutil/trace.go:171","msg":"trace[917005219] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-7sg67; range_end:; response_count:1; response_revision:639; }","duration":"608.157339ms","start":"2026-02-18T22:54:15.505823Z","end":"2026-02-18T22:54:16.11398Z","steps":["trace[917005219] 'agreement among raft nodes before linearized reading' (duration: 608.128038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.114007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.50582Z","time spent":"608.181649ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4719,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-7sg67\" "} {"level":"warn","ts":"2026-02-18T22:54:16.114107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.290482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-7fdz7\" ","response":"range_response_count:1 size:3849"} {"level":"info","ts":"2026-02-18T22:54:16.114122Z","caller":"traceutil/trace.go:171","msg":"trace[2137602093] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-7fdz7; range_end:; response_count:1; response_revision:639; }","duration":"608.314383ms","start":"2026-02-18T22:54:15.505803Z","end":"2026-02-18T22:54:16.114118Z","steps":["trace[2137602093] 'agreement among raft nodes before linearized reading' (duration: 608.263792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:16.114137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:15.505798Z","time spent":"608.335634ms","remote":"127.0.0.1:36382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3871,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-7fdz7\" "} {"level":"info","ts":"2026-02-18T22:54:16.689775Z","caller":"traceutil/trace.go:171","msg":"trace[319141801] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"288.687807ms","start":"2026-02-18T22:54:16.401072Z","end":"2026-02-18T22:54:16.68976Z","steps":["trace[319141801] 'process raft request' (duration: 288.606615ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:16.839026Z","caller":"traceutil/trace.go:171","msg":"trace[262548609] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"123.818621ms","start":"2026-02-18T22:54:16.715189Z","end":"2026-02-18T22:54:16.839007Z","steps":["trace[262548609] 'process raft request' (duration: 79.406847ms)","trace[262548609] 'compare' (duration: 44.124917ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:16.885958Z","caller":"traceutil/trace.go:171","msg":"trace[840551142] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"170.54895ms","start":"2026-02-18T22:54:16.715392Z","end":"2026-02-18T22:54:16.885941Z","steps":["trace[840551142] 'process raft request' (duration: 170.519679ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:16.886206Z","caller":"traceutil/trace.go:171","msg":"trace[493072007] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"170.834537ms","start":"2026-02-18T22:54:16.715365Z","end":"2026-02-18T22:54:16.8862Z","steps":["trace[493072007] 'process raft request' (duration: 170.450208ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:20.309593Z","caller":"traceutil/trace.go:171","msg":"trace[101450852] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"127.679301ms","start":"2026-02-18T22:54:20.181894Z","end":"2026-02-18T22:54:20.309573Z","steps":["trace[101450852] 'process raft request' (duration: 127.274692ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:20.309649Z","caller":"traceutil/trace.go:171","msg":"trace[520662910] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"127.1772ms","start":"2026-02-18T22:54:20.182436Z","end":"2026-02-18T22:54:20.309613Z","steps":["trace[520662910] 'process raft request' (duration: 126.856093ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:22.544822Z","caller":"traceutil/trace.go:171","msg":"trace[442614609] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"334.395891ms","start":"2026-02-18T22:54:22.210409Z","end":"2026-02-18T22:54:22.544805Z","steps":["trace[442614609] 'read index received' (duration: 334.264758ms)","trace[442614609] 'applied index is now lower than readState.Index' (duration: 130.623µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:22.544995Z","caller":"traceutil/trace.go:171","msg":"trace[1418125463] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"347.810597ms","start":"2026-02-18T22:54:22.197171Z","end":"2026-02-18T22:54:22.544981Z","steps":["trace[1418125463] 'process raft request' (duration: 347.555911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:22.545087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.19716Z","time spent":"347.860618ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:22.545251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.839131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:22.545293Z","caller":"traceutil/trace.go:171","msg":"trace[981263314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"334.906123ms","start":"2026-02-18T22:54:22.21038Z","end":"2026-02-18T22:54:22.545286Z","steps":["trace[981263314] 'agreement among raft nodes before linearized reading' (duration: 334.844472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:22.545314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.210363Z","time spent":"334.946764ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:22.545467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.052939ms","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:54:22.545493Z","caller":"traceutil/trace.go:171","msg":"trace[1909384653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:698; }","duration":"273.113821ms","start":"2026-02-18T22:54:22.272373Z","end":"2026-02-18T22:54:22.545487Z","steps":["trace[1909384653] 'agreement among raft nodes before linearized reading' (duration: 273.034869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:22.545661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.133703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:54:22.545691Z","caller":"traceutil/trace.go:171","msg":"trace[376210878] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:698; }","duration":"188.186434ms","start":"2026-02-18T22:54:22.357497Z","end":"2026-02-18T22:54:22.545684Z","steps":["trace[376210878] 'agreement among raft nodes before linearized reading' (duration: 188.138253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:22.545818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.237585ms","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:54:22.545841Z","caller":"traceutil/trace.go:171","msg":"trace[158781499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:698; }","duration":"231.279147ms","start":"2026-02-18T22:54:22.314556Z","end":"2026-02-18T22:54:22.545835Z","steps":["trace[158781499] 'agreement among raft nodes before linearized reading' (duration: 231.220536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:22.545939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.912641ms","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:54:22.545961Z","caller":"traceutil/trace.go:171","msg":"trace[1893147978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:698; }","duration":"231.962673ms","start":"2026-02-18T22:54:22.313992Z","end":"2026-02-18T22:54:22.545955Z","steps":["trace[1893147978] 'agreement among raft nodes before linearized reading' (duration: 231.912582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:23.348808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.380921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:23.348885Z","caller":"traceutil/trace.go:171","msg":"trace[205012439] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"796.148129ms","start":"2026-02-18T22:54:22.552717Z","end":"2026-02-18T22:54:23.348866Z","steps":["trace[205012439] 'process raft request' (duration: 188.657196ms)","trace[205012439] 'compare' (duration: 607.176885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:23.348936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.552706Z","time spent":"796.20809ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:24.814693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201929149s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:24.814843Z","caller":"traceutil/trace.go:171","msg":"trace[468035131] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:718; }","duration":"1.604170305s","start":"2026-02-18T22:54:23.210658Z","end":"2026-02-18T22:54:24.814828Z","steps":["trace[468035131] 'read index received' (duration: 401.96112ms)","trace[468035131] 'applied index is now lower than readState.Index' (duration: 1.202208325s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:24.815058Z","caller":"traceutil/trace.go:171","msg":"trace[1136143980] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"2.260746512s","start":"2026-02-18T22:54:22.554299Z","end":"2026-02-18T22:54:24.815046Z","steps":["trace[1136143980] 'process raft request' (duration: 1.058398434s)","trace[1136143980] 'compare' (duration: 1.200578868s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:24.815118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.554269Z","time spent":"2.260817175s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:24.815289Z","caller":"traceutil/trace.go:171","msg":"trace[1285957318] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"2.26064371s","start":"2026-02-18T22:54:22.554637Z","end":"2026-02-18T22:54:24.815281Z","steps":["trace[1285957318] 'process raft request' (duration: 2.260137217s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.815351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:22.55463Z","time spent":"2.260680151s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:24.815559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.604887253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.815589Z","caller":"traceutil/trace.go:171","msg":"trace[617398394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"1.604952904s","start":"2026-02-18T22:54:23.210627Z","end":"2026-02-18T22:54:24.815579Z","steps":["trace[617398394] 'agreement among raft nodes before linearized reading' (duration: 1.604893732s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.815616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.210602Z","time spent":"1.605008526s","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:24.81583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.252102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:24.815856Z","caller":"traceutil/trace.go:171","msg":"trace[1510359086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:701; }","duration":"617.278413ms","start":"2026-02-18T22:54:24.19857Z","end":"2026-02-18T22:54:24.815848Z","steps":["trace[1510359086] 'agreement among raft nodes before linearized reading' (duration: 617.180101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.81588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.198554Z","time spent":"617.320695ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:54:24.816505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.948986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-18T22:54:24.816539Z","caller":"traceutil/trace.go:171","msg":"trace[2080943838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:701; }","duration":"267.011037ms","start":"2026-02-18T22:54:24.549519Z","end":"2026-02-18T22:54:24.81653Z","steps":["trace[2080943838] 'agreement among raft nodes before linearized reading' (duration: 266.622338ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:24.952261Z","caller":"traceutil/trace.go:171","msg":"trace[2028631162] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"126.575565ms","start":"2026-02-18T22:54:24.82567Z","end":"2026-02-18T22:54:24.952245Z","steps":["trace[2028631162] 'read index received' (duration: 65.89938ms)","trace[2028631162] 'applied index is now lower than readState.Index' (duration: 60.675705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:24.95235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.659987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.952366Z","caller":"traceutil/trace.go:171","msg":"trace[436324346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"126.693059ms","start":"2026-02-18T22:54:24.825668Z","end":"2026-02-18T22:54:24.952361Z","steps":["trace[436324346] 'agreement among raft nodes before linearized reading' (duration: 126.635787ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:24.952577Z","caller":"traceutil/trace.go:171","msg":"trace[682987798] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"130.824496ms","start":"2026-02-18T22:54:24.821747Z","end":"2026-02-18T22:54:24.952571Z","steps":["trace[682987798] 'process raft request' (duration: 99.15384ms)","trace[682987798] 'compare' (duration: 31.266625ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:25.959885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.397164ms","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:54:25.959933Z","caller":"traceutil/trace.go:171","msg":"trace[1482158884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:703; }","duration":"608.479016ms","start":"2026-02-18T22:54:25.351443Z","end":"2026-02-18T22:54:25.959922Z","steps":["trace[1482158884] 'range keys from in-memory index tree' (duration: 608.283222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.959955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:25.35143Z","time spent":"608.520047ms","remote":"127.0.0.1:36454","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:54:25.960127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.916742ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:25.960161Z","caller":"traceutil/trace.go:171","msg":"trace[1640820383] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:703; }","duration":"171.953803ms","start":"2026-02-18T22:54:25.788204Z","end":"2026-02-18T22:54:25.960157Z","steps":["trace[1640820383] 'range keys from in-memory index tree' (duration: 171.911092ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:26.216361Z","caller":"traceutil/trace.go:171","msg":"trace[717185679] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"247.774767ms","start":"2026-02-18T22:54:25.968569Z","end":"2026-02-18T22:54:26.216344Z","steps":["trace[717185679] 'process raft request' (duration: 247.631513ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:27.075292Z","caller":"traceutil/trace.go:171","msg":"trace[761638818] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"118.968106ms","start":"2026-02-18T22:54:26.956304Z","end":"2026-02-18T22:54:27.075273Z","steps":["trace[761638818] 'read index received' (duration: 105.690665ms)","trace[761638818] 'applied index is now lower than readState.Index' (duration: 13.276901ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:27.075427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.10357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:27.075443Z","caller":"traceutil/trace.go:171","msg":"trace[480329751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"119.15227ms","start":"2026-02-18T22:54:26.956286Z","end":"2026-02-18T22:54:27.075438Z","steps":["trace[480329751] 'agreement among raft nodes before linearized reading' (duration: 119.054318ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:27.075562Z","caller":"traceutil/trace.go:171","msg":"trace[1935184062] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"126.059314ms","start":"2026-02-18T22:54:26.949482Z","end":"2026-02-18T22:54:27.075542Z","steps":["trace[1935184062] 'process raft request' (duration: 112.504676ms)","trace[1935184062] 'compare' (duration: 13.190009ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:27.112341Z","caller":"traceutil/trace.go:171","msg":"trace[1946713402] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"134.831121ms","start":"2026-02-18T22:54:26.977487Z","end":"2026-02-18T22:54:27.112318Z","steps":["trace[1946713402] 'process raft request' (duration: 134.703797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:41.48247Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338819128707140,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:41.544163Z","caller":"traceutil/trace.go:171","msg":"trace[1245138022] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"562.144982ms","start":"2026-02-18T22:54:40.981998Z","end":"2026-02-18T22:54:41.544143Z","steps":["trace[1245138022] 'read index received' (duration: 561.981318ms)","trace[1245138022] 'applied index is now lower than readState.Index' (duration: 162.234µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:41.544478Z","caller":"traceutil/trace.go:171","msg":"trace[489066545] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"611.629161ms","start":"2026-02-18T22:54:40.932839Z","end":"2026-02-18T22:54:41.544468Z","steps":["trace[489066545] 'process raft request' (duration: 611.189811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:41.544564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:40.932828Z","time spent":"611.680772ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:41.544884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.306654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-18T22:54:41.544884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.858438ms","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:54:41.544917Z","caller":"traceutil/trace.go:171","msg":"trace[1871097276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"545.361636ms","start":"2026-02-18T22:54:40.999548Z","end":"2026-02-18T22:54:41.54491Z","steps":["trace[1871097276] 'agreement among raft nodes before linearized reading' (duration: 545.252942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:41.544945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:40.999537Z","time spent":"545.403237ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:54:41.544951Z","caller":"traceutil/trace.go:171","msg":"trace[2044371793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"562.968191ms","start":"2026-02-18T22:54:40.981968Z","end":"2026-02-18T22:54:41.544936Z","steps":["trace[2044371793] 'agreement among raft nodes before linearized reading' (duration: 562.760065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:41.544996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:40.981956Z","time spent":"563.032724ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:41.545033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.587815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:41.545051Z","caller":"traceutil/trace.go:171","msg":"trace[1821748272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"331.615936ms","start":"2026-02-18T22:54:41.21343Z","end":"2026-02-18T22:54:41.545046Z","steps":["trace[1821748272] 'agreement among raft nodes before linearized reading' (duration: 331.588075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:41.545066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:41.213424Z","time spent":"331.638786ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:41.54521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.811694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:41.545234Z","caller":"traceutil/trace.go:171","msg":"trace[1776666916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"332.859055ms","start":"2026-02-18T22:54:41.212368Z","end":"2026-02-18T22:54:41.545227Z","steps":["trace[1776666916] 'agreement among raft nodes before linearized reading' (duration: 332.794564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:41.545256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:41.212356Z","time spent":"332.896466ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T22:54:41.68768Z","caller":"traceutil/trace.go:171","msg":"trace[233512565] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"135.247955ms","start":"2026-02-18T22:54:41.552417Z","end":"2026-02-18T22:54:41.687665Z","steps":["trace[233512565] 'process raft request' (duration: 133.30917ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:41.689079Z","caller":"traceutil/trace.go:171","msg":"trace[1324536149] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:775; }","duration":"104.335224ms","start":"2026-02-18T22:54:41.584728Z","end":"2026-02-18T22:54:41.689063Z","steps":["trace[1324536149] 'read index received' (duration: 101.005406ms)","trace[1324536149] 'applied index is now lower than readState.Index' (duration: 3.328008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:41.689177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.437558ms","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:54:41.689202Z","caller":"traceutil/trace.go:171","msg":"trace[1266296287] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:755; }","duration":"104.502139ms","start":"2026-02-18T22:54:41.584694Z","end":"2026-02-18T22:54:41.689196Z","steps":["trace[1266296287] 'agreement among raft nodes before linearized reading' (duration: 104.432217ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:41.689408Z","caller":"traceutil/trace.go:171","msg":"trace[1962340605] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"136.72707ms","start":"2026-02-18T22:54:41.552674Z","end":"2026-02-18T22:54:41.689401Z","steps":["trace[1962340605] 'process raft request' (duration: 136.26923ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:41.689493Z","caller":"traceutil/trace.go:171","msg":"trace[1525497456] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"136.72782ms","start":"2026-02-18T22:54:41.55276Z","end":"2026-02-18T22:54:41.689488Z","steps":["trace[1525497456] 'process raft request' (duration: 136.255269ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:43.710211Z","caller":"traceutil/trace.go:171","msg":"trace[943101506] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"156.719053ms","start":"2026-02-18T22:54:43.553472Z","end":"2026-02-18T22:54:43.710191Z","steps":["trace[943101506] 'process raft request' (duration: 156.459567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:44.316598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.801809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:44.316733Z","caller":"traceutil/trace.go:171","msg":"trace[593675661] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"601.209736ms","start":"2026-02-18T22:54:43.715506Z","end":"2026-02-18T22:54:44.316716Z","steps":["trace[593675661] 'process raft request' (duration: 479.218564ms)","trace[593675661] 'compare' (duration: 121.498351ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:44.316791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:43.715494Z","time spent":"601.269177ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:44.71458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338819128707166,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:45.108391Z","caller":"traceutil/trace.go:171","msg":"trace[499690849] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"894.647209ms","start":"2026-02-18T22:54:44.213727Z","end":"2026-02-18T22:54:45.108374Z","steps":["trace[499690849] 'read index received' (duration: 893.123353ms)","trace[499690849] 'applied index is now lower than readState.Index' (duration: 1.523206ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:45.108569Z","caller":"traceutil/trace.go:171","msg":"trace[1849623568] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"1.390982447s","start":"2026-02-18T22:54:43.717579Z","end":"2026-02-18T22:54:45.108561Z","steps":["trace[1849623568] 'process raft request' (duration: 1.389324377s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.108674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:43.717568Z","time spent":"1.391023659s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:45.108799Z","caller":"traceutil/trace.go:171","msg":"trace[1880878815] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.390549057s","start":"2026-02-18T22:54:43.718243Z","end":"2026-02-18T22:54:45.108792Z","steps":["trace[1880878815] 'process raft request' (duration: 1.390097207s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.11043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:43.718233Z","time spent":"1.390580068s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:45.110662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.928804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:45.110697Z","caller":"traceutil/trace.go:171","msg":"trace[2060768282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"896.989054ms","start":"2026-02-18T22:54:44.213698Z","end":"2026-02-18T22:54:45.110687Z","steps":["trace[2060768282] 'agreement among raft nodes before linearized reading' (duration: 896.873582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.110724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:44.213684Z","time spent":"897.033005ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:45.110901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.681074ms","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:54:45.110921Z","caller":"traceutil/trace.go:171","msg":"trace[694674945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"807.736516ms","start":"2026-02-18T22:54:44.303178Z","end":"2026-02-18T22:54:45.110914Z","steps":["trace[694674945] 'agreement among raft nodes before linearized reading' (duration: 807.657754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:45.110939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:44.30316Z","time spent":"807.775326ms","remote":"127.0.0.1:36454","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:54:45.685293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.635248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:45.685391Z","caller":"traceutil/trace.go:171","msg":"trace[232353482] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"562.259165ms","start":"2026-02-18T22:54:45.123114Z","end":"2026-02-18T22:54:45.685373Z","steps":["trace[232353482] 'process raft request' (duration: 290.445812ms)","trace[232353482] 'compare' (duration: 271.473454ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:45.685447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:45.123098Z","time spent":"562.322576ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:45.969899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.005491ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:45.970035Z","caller":"traceutil/trace.go:171","msg":"trace[812651234] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:762; }","duration":"182.156524ms","start":"2026-02-18T22:54:45.787859Z","end":"2026-02-18T22:54:45.970015Z","steps":["trace[812651234] 'range keys from in-memory index tree' (duration: 181.99117ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:45.97029Z","caller":"traceutil/trace.go:171","msg":"trace[875409470] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"251.126903ms","start":"2026-02-18T22:54:45.719148Z","end":"2026-02-18T22:54:45.970275Z","steps":["trace[875409470] 'process raft request' (duration: 211.042126ms)","trace[875409470] 'compare' (duration: 39.675688ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:57.85927Z","caller":"traceutil/trace.go:171","msg":"trace[565008484] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"191.145626ms","start":"2026-02-18T22:54:57.668107Z","end":"2026-02-18T22:54:57.859253Z","steps":["trace[565008484] 'process raft request' (duration: 190.830509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:58.717902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.926675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:58.717972Z","caller":"traceutil/trace.go:171","msg":"trace[1677623931] linearizableReadLoop","detail":"{readStateIndex:876; appliedIndex:875; }","duration":"693.794823ms","start":"2026-02-18T22:54:58.024165Z","end":"2026-02-18T22:54:58.71796Z","steps":["trace[1677623931] 'read index received' (duration: 5.721155ms)","trace[1677623931] 'applied index is now lower than readState.Index' (duration: 688.072828ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:58.718151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.85769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:58.718173Z","caller":"traceutil/trace.go:171","msg":"trace[1891884177] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:847; }","duration":"609.901201ms","start":"2026-02-18T22:54:58.108263Z","end":"2026-02-18T22:54:58.718164Z","steps":["trace[1891884177] 'agreement among raft nodes before linearized reading' (duration: 609.86251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:58.718172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.988668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-18T22:54:58.718195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.108251Z","time spent":"609.938111ms","remote":"127.0.0.1:36414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:54:58.718219Z","caller":"traceutil/trace.go:171","msg":"trace[1169217753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:847; }","duration":"694.093511ms","start":"2026-02-18T22:54:58.024114Z","end":"2026-02-18T22:54:58.718208Z","steps":["trace[1169217753] 'agreement among raft nodes before linearized reading' (duration: 693.943127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:58.718244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.0241Z","time spent":"694.139262ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:54:58.718364Z","caller":"traceutil/trace.go:171","msg":"trace[220268029] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"856.17112ms","start":"2026-02-18T22:54:57.862186Z","end":"2026-02-18T22:54:58.718357Z","steps":["trace[220268029] 'process raft request' (duration: 167.733093ms)","trace[220268029] 'compare' (duration: 687.839333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:58.71841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.498406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-18T22:54:58.718419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:57.862168Z","time spent":"856.208991ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:58.718442Z","caller":"traceutil/trace.go:171","msg":"trace[94684206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:847; }","duration":"321.552058ms","start":"2026-02-18T22:54:58.396874Z","end":"2026-02-18T22:54:58.718426Z","steps":["trace[94684206] 'agreement among raft nodes before linearized reading' (duration: 321.465716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:58.718463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.396859Z","time spent":"321.597519ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-18T22:54:58.718514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.515289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:58.718528Z","caller":"traceutil/trace.go:171","msg":"trace[1323774203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"509.55108ms","start":"2026-02-18T22:54:58.208972Z","end":"2026-02-18T22:54:58.718523Z","steps":["trace[1323774203] 'agreement among raft nodes before linearized reading' (duration: 509.52717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:58.718543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.20896Z","time spent":"509.579661ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:54:59.58534Z","caller":"traceutil/trace.go:171","msg":"trace[117728570] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"289.378847ms","start":"2026-02-18T22:54:59.295946Z","end":"2026-02-18T22:54:59.585325Z","steps":["trace[117728570] 'read index received' (duration: 289.279584ms)","trace[117728570] 'applied index is now lower than readState.Index' (duration: 98.573µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:59.585488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.52384ms","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:54:59.585511Z","caller":"traceutil/trace.go:171","msg":"trace[1155841548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"289.576042ms","start":"2026-02-18T22:54:59.29593Z","end":"2026-02-18T22:54:59.585506Z","steps":["trace[1155841548] 'agreement among raft nodes before linearized reading' (duration: 289.47424ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:59.585532Z","caller":"traceutil/trace.go:171","msg":"trace[673604307] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"298.83982ms","start":"2026-02-18T22:54:59.286672Z","end":"2026-02-18T22:54:59.585512Z","steps":["trace[673604307] 'process raft request' (duration: 298.545733ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:01.02732Z","caller":"traceutil/trace.go:171","msg":"trace[1873779955] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:880; }","duration":"236.852456ms","start":"2026-02-18T22:55:00.790446Z","end":"2026-02-18T22:55:01.027298Z","steps":["trace[1873779955] 'read index received' (duration: 236.693122ms)","trace[1873779955] 'applied index is now lower than readState.Index' (duration: 158.004µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:01.027569Z","caller":"traceutil/trace.go:171","msg":"trace[859504348] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"295.563253ms","start":"2026-02-18T22:55:00.731994Z","end":"2026-02-18T22:55:01.027557Z","steps":["trace[859504348] 'process raft request' (duration: 295.158674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:01.028171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.085825ms","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:55:01.02824Z","caller":"traceutil/trace.go:171","msg":"trace[1083274480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"227.189958ms","start":"2026-02-18T22:55:00.801033Z","end":"2026-02-18T22:55:01.028223Z","steps":["trace[1083274480] 'agreement among raft nodes before linearized reading' (duration: 226.991013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:01.029807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.349835ms","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:55:01.029912Z","caller":"traceutil/trace.go:171","msg":"trace[1622838301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"239.504279ms","start":"2026-02-18T22:55:00.790395Z","end":"2026-02-18T22:55:01.029899Z","steps":["trace[1622838301] 'agreement among raft nodes before linearized reading' (duration: 237.352088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.241975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.3926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:02.242038Z","caller":"traceutil/trace.go:171","msg":"trace[180508872] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"1.208726211s","start":"2026-02-18T22:55:01.0333Z","end":"2026-02-18T22:55:02.242027Z","steps":["trace[180508872] 'process raft request' (duration: 334.234858ms)","trace[180508872] 'compare' (duration: 873.95283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:02.242069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.033254Z","time spent":"1.208801812s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:02.298679Z","caller":"traceutil/trace.go:171","msg":"trace[1875672486] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:881; }","duration":"1.085741625s","start":"2026-02-18T22:55:01.212923Z","end":"2026-02-18T22:55:02.298664Z","steps":["trace[1875672486] 'read index received' (duration: 154.622704ms)","trace[1875672486] 'applied index is now lower than readState.Index' (duration: 931.118251ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:02.298858Z","caller":"traceutil/trace.go:171","msg":"trace[2130942843] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"1.263832962s","start":"2026-02-18T22:55:01.035019Z","end":"2026-02-18T22:55:02.298852Z","steps":["trace[2130942843] 'process raft request' (duration: 1.263572236s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.298928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.034969Z","time spent":"1.263906064s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:02.299119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086203406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:02.299143Z","caller":"traceutil/trace.go:171","msg":"trace[695033917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:854; }","duration":"1.086265107s","start":"2026-02-18T22:55:01.212873Z","end":"2026-02-18T22:55:02.299138Z","steps":["trace[695033917] 'agreement among raft nodes before linearized reading' (duration: 1.086224506s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.299159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.212853Z","time spent":"1.086301388s","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:55:02.300247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.49862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:02.300265Z","caller":"traceutil/trace.go:171","msg":"trace[823948417] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:855; }","duration":"821.542702ms","start":"2026-02-18T22:55:01.478717Z","end":"2026-02-18T22:55:02.30026Z","steps":["trace[823948417] 'agreement among raft nodes before linearized reading' (duration: 821.502261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.300281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.478705Z","time spent":"821.571042ms","remote":"127.0.0.1:36346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:55:02.300394Z","caller":"traceutil/trace.go:171","msg":"trace[759985379] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"192.321925ms","start":"2026-02-18T22:55:02.108066Z","end":"2026-02-18T22:55:02.300388Z","steps":["trace[759985379] 'process raft request' (duration: 192.124409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.300499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.125502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:55:02.300513Z","caller":"traceutil/trace.go:171","msg":"trace[767394806] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:855; }","duration":"504.167643ms","start":"2026-02-18T22:55:01.79634Z","end":"2026-02-18T22:55:02.300508Z","steps":["trace[767394806] 'agreement among raft nodes before linearized reading' (duration: 504.106261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.300525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.796325Z","time spent":"504.197494ms","remote":"127.0.0.1:36220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"warn","ts":"2026-02-18T22:55:02.300567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.230202ms","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:55:02.300606Z","caller":"traceutil/trace.go:171","msg":"trace[937170048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:855; }","duration":"707.285633ms","start":"2026-02-18T22:55:01.593312Z","end":"2026-02-18T22:55:02.300598Z","steps":["trace[937170048] 'agreement among raft nodes before linearized reading' (duration: 707.18922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.300624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.593306Z","time spent":"707.313683ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:02.300738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.196379ms","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:55:02.300755Z","caller":"traceutil/trace.go:171","msg":"trace[835514161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:855; }","duration":"712.23632ms","start":"2026-02-18T22:55:01.588514Z","end":"2026-02-18T22:55:02.30075Z","steps":["trace[835514161] 'agreement among raft nodes before linearized reading' (duration: 712.192139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:02.300768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.5885Z","time spent":"712.264511ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-18T22:55:12.549821Z","caller":"traceutil/trace.go:171","msg":"trace[1845079579] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"174.444552ms","start":"2026-02-18T22:55:12.375362Z","end":"2026-02-18T22:55:12.549806Z","steps":["trace[1845079579] 'process raft request' (duration: 174.342669ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:12.549852Z","caller":"traceutil/trace.go:171","msg":"trace[1291932961] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:914; }","duration":"144.101934ms","start":"2026-02-18T22:55:12.405731Z","end":"2026-02-18T22:55:12.549833Z","steps":["trace[1291932961] 'read index received' (duration: 144.095464ms)","trace[1291932961] 'applied index is now lower than readState.Index' (duration: 5.41µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:12.550034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.287539ms","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:55:12.550052Z","caller":"traceutil/trace.go:171","msg":"trace[1562076690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:882; }","duration":"144.35928ms","start":"2026-02-18T22:55:12.405687Z","end":"2026-02-18T22:55:12.550046Z","steps":["trace[1562076690] 'agreement among raft nodes before linearized reading' (duration: 144.228797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.276985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.696605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:13.277047Z","caller":"traceutil/trace.go:171","msg":"trace[1498593411] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"727.133701ms","start":"2026-02-18T22:55:12.549903Z","end":"2026-02-18T22:55:13.277037Z","steps":["trace[1498593411] 'read index received' (duration: 426.341903ms)","trace[1498593411] 'applied index is now lower than readState.Index' (duration: 300.788668ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:13.277137Z","caller":"traceutil/trace.go:171","msg":"trace[1860722138] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"827.609025ms","start":"2026-02-18T22:55:12.449521Z","end":"2026-02-18T22:55:13.27713Z","steps":["trace[1860722138] 'process raft request' (duration: 526.714015ms)","trace[1860722138] 'compare' (duration: 300.552962ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:13.277196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:12.449505Z","time spent":"827.645967ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:13.277346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.704776ms","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:55:13.277364Z","caller":"traceutil/trace.go:171","msg":"trace[959463031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"858.465704ms","start":"2026-02-18T22:55:12.418894Z","end":"2026-02-18T22:55:13.27736Z","steps":["trace[959463031] 'agreement among raft nodes before linearized reading' (duration: 858.400432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.277379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:12.418887Z","time spent":"858.488104ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:13.277453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.864558ms","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:55:13.27747Z","caller":"traceutil/trace.go:171","msg":"trace[311611845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"862.893209ms","start":"2026-02-18T22:55:12.414572Z","end":"2026-02-18T22:55:13.277466Z","steps":["trace[311611845] 'agreement among raft nodes before linearized reading' (duration: 862.854068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.277482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:12.414565Z","time spent":"862.913869ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:55:13.339819Z","caller":"traceutil/trace.go:171","msg":"trace[1938026863] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"786.806361ms","start":"2026-02-18T22:55:12.552997Z","end":"2026-02-18T22:55:13.339804Z","steps":["trace[1938026863] 'process raft request' (duration: 786.706969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:13.339931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:12.552984Z","time spent":"786.889352ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:13.340257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.860011ms","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:55:13.34028Z","caller":"traceutil/trace.go:171","msg":"trace[2097967599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:884; }","duration":"185.905112ms","start":"2026-02-18T22:55:13.154369Z","end":"2026-02-18T22:55:13.340274Z","steps":["trace[2097967599] 'agreement among raft nodes before linearized reading' (duration: 185.83727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:18.858791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.212829ms","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:55:18.858867Z","caller":"traceutil/trace.go:171","msg":"trace[974274284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"227.329012ms","start":"2026-02-18T22:55:18.631521Z","end":"2026-02-18T22:55:18.85885Z","steps":["trace[974274284] 'range keys from in-memory index tree' (duration: 227.089176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:19.843558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.706905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:19.843642Z","caller":"traceutil/trace.go:171","msg":"trace[2111302594] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"366.998012ms","start":"2026-02-18T22:55:19.476628Z","end":"2026-02-18T22:55:19.843626Z","steps":["trace[2111302594] 'process raft request' (duration: 160.160734ms)","trace[2111302594] 'compare' (duration: 206.607082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:19.8438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.476614Z","time spent":"367.161855ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:19.854678Z","caller":"traceutil/trace.go:171","msg":"trace[1262791805] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:933; }","duration":"316.15026ms","start":"2026-02-18T22:55:19.538507Z","end":"2026-02-18T22:55:19.854658Z","steps":["trace[1262791805] 'read index received' (duration: 98.321593ms)","trace[1262791805] 'applied index is now lower than readState.Index' (duration: 217.827827ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:19.854872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.333324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:19.854915Z","caller":"traceutil/trace.go:171","msg":"trace[765170842] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:903; }","duration":"316.436117ms","start":"2026-02-18T22:55:19.538467Z","end":"2026-02-18T22:55:19.854903Z","steps":["trace[765170842] 'agreement among raft nodes before linearized reading' (duration: 316.277083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:19.854952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.538444Z","time spent":"316.500228ms","remote":"127.0.0.1:36716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":86,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:55:19.85537Z","caller":"traceutil/trace.go:171","msg":"trace[513622556] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"378.611426ms","start":"2026-02-18T22:55:19.476683Z","end":"2026-02-18T22:55:19.855294Z","steps":["trace[513622556] 'process raft request' (duration: 377.846428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:19.855491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.476666Z","time spent":"378.741529ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:19.855924Z","caller":"traceutil/trace.go:171","msg":"trace[385176532] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"378.920153ms","start":"2026-02-18T22:55:19.476748Z","end":"2026-02-18T22:55:19.855668Z","steps":["trace[385176532] 'process raft request' (duration: 377.872649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:19.856007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:19.476744Z","time spent":"379.22591ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:20.254702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.43291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:20.254779Z","caller":"traceutil/trace.go:171","msg":"trace[2022575742] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:903; }","duration":"146.586384ms","start":"2026-02-18T22:55:20.108164Z","end":"2026-02-18T22:55:20.254751Z","steps":["trace[2022575742] 'count revisions from in-memory index tree' (duration: 146.365549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.398208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.483198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:24.398412Z","caller":"traceutil/trace.go:171","msg":"trace[1885721440] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"172.040946ms","start":"2026-02-18T22:55:24.226355Z","end":"2026-02-18T22:55:24.398395Z","steps":["trace[1885721440] 'process raft request' (duration: 172.011526ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:24.398407Z","caller":"traceutil/trace.go:171","msg":"trace[479307453] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"188.257668ms","start":"2026-02-18T22:55:24.210128Z","end":"2026-02-18T22:55:24.398385Z","steps":["trace[479307453] 'process raft request' (duration: 42.520595ms)","trace[479307453] 'compare' (duration: 145.301253ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:24.398557Z","caller":"traceutil/trace.go:171","msg":"trace[1422820070] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"185.068993ms","start":"2026-02-18T22:55:24.213481Z","end":"2026-02-18T22:55:24.39855Z","steps":["trace[1422820070] 'read index received' (duration: 39.174286ms)","trace[1422820070] 'applied index is now lower than readState.Index' (duration: 145.893877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:24.398598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.112854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:24.398614Z","caller":"traceutil/trace.go:171","msg":"trace[359861236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"185.148514ms","start":"2026-02-18T22:55:24.21346Z","end":"2026-02-18T22:55:24.398608Z","steps":["trace[359861236] 'agreement among raft nodes before linearized reading' (duration: 185.116104ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:24.401775Z","caller":"traceutil/trace.go:171","msg":"trace[1163078563] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"186.61489ms","start":"2026-02-18T22:55:24.215149Z","end":"2026-02-18T22:55:24.401764Z","steps":["trace[1163078563] 'process raft request' (duration: 183.144318ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:25.731995Z","caller":"traceutil/trace.go:171","msg":"trace[1648649508] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"143.396859ms","start":"2026-02-18T22:55:25.588535Z","end":"2026-02-18T22:55:25.731931Z","steps":["trace[1648649508] 'process raft request' (duration: 143.247675ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:28.444332Z","caller":"traceutil/trace.go:171","msg":"trace[1188986457] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"491.543404ms","start":"2026-02-18T22:55:27.952756Z","end":"2026-02-18T22:55:28.444299Z","steps":["trace[1188986457] 'read index received' (duration: 491.310678ms)","trace[1188986457] 'applied index is now lower than readState.Index' (duration: 231.616µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:28.444754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.978984ms","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:55:28.444809Z","caller":"traceutil/trace.go:171","msg":"trace[59733500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:950; }","duration":"492.073216ms","start":"2026-02-18T22:55:27.952722Z","end":"2026-02-18T22:55:28.444795Z","steps":["trace[59733500] 'agreement among raft nodes before linearized reading' (duration: 491.880972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.444924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.952707Z","time spent":"492.203169ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-18T22:55:28.445136Z","caller":"traceutil/trace.go:171","msg":"trace[1886311192] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"522.770202ms","start":"2026-02-18T22:55:27.922352Z","end":"2026-02-18T22:55:28.445123Z","steps":["trace[1886311192] 'process raft request' (duration: 521.781959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.445241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.922344Z","time spent":"522.844433ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:28.44565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.856566ms","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:55:28.445694Z","caller":"traceutil/trace.go:171","msg":"trace[1161437113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:950; }","duration":"404.933718ms","start":"2026-02-18T22:55:28.040749Z","end":"2026-02-18T22:55:28.445683Z","steps":["trace[1161437113] 'agreement among raft nodes before linearized reading' (duration: 404.828836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.445729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:28.04073Z","time spent":"404.99137ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:36.508437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338819128707727,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:55:36.750663Z","caller":"traceutil/trace.go:171","msg":"trace[1901179418] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"742.935621ms","start":"2026-02-18T22:55:36.007705Z","end":"2026-02-18T22:55:36.75064Z","steps":["trace[1901179418] 'read index received' (duration: 742.761907ms)","trace[1901179418] 'applied index is now lower than readState.Index' (duration: 172.844µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:36.751051Z","caller":"traceutil/trace.go:171","msg":"trace[2082272167] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"961.773974ms","start":"2026-02-18T22:55:35.789261Z","end":"2026-02-18T22:55:36.751035Z","steps":["trace[2082272167] 'process raft request' (duration: 961.264492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.75133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:35.789244Z","time spent":"961.970929ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:36.751945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.775208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:36.75206Z","caller":"traceutil/trace.go:171","msg":"trace[1895425205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"538.898361ms","start":"2026-02-18T22:55:36.213104Z","end":"2026-02-18T22:55:36.752002Z","steps":["trace[1895425205] 'agreement among raft nodes before linearized reading' (duration: 538.784718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.752165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.21309Z","time spent":"539.010183ms","remote":"127.0.0.1:36190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:55:36.752543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.996261ms","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:55:36.752583Z","caller":"traceutil/trace.go:171","msg":"trace[837370485] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"234.118344ms","start":"2026-02-18T22:55:36.518455Z","end":"2026-02-18T22:55:36.752573Z","steps":["trace[837370485] 'agreement among raft nodes before linearized reading' (duration: 233.998551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.753143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.943677ms","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:55:36.753185Z","caller":"traceutil/trace.go:171","msg":"trace[1544246372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"254.999548ms","start":"2026-02-18T22:55:36.498175Z","end":"2026-02-18T22:55:36.753175Z","steps":["trace[1544246372] 'agreement among raft nodes before linearized reading' (duration: 254.891025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.753698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.49406ms","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:55:36.753737Z","caller":"traceutil/trace.go:171","msg":"trace[333791394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"266.555101ms","start":"2026-02-18T22:55:36.487173Z","end":"2026-02-18T22:55:36.753728Z","steps":["trace[333791394] 'agreement among raft nodes before linearized reading' (duration: 266.456979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.754238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.410472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-18T22:55:36.75428Z","caller":"traceutil/trace.go:171","msg":"trace[625947541] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1008; }","duration":"286.486664ms","start":"2026-02-18T22:55:36.467784Z","end":"2026-02-18T22:55:36.754271Z","steps":["trace[625947541] 'agreement among raft nodes before linearized reading' (duration: 286.382951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.755039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.130305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-18T22:55:36.755084Z","caller":"traceutil/trace.go:171","msg":"trace[845725778] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1008; }","duration":"403.242137ms","start":"2026-02-18T22:55:36.35183Z","end":"2026-02-18T22:55:36.755072Z","steps":["trace[845725778] 'agreement among raft nodes before linearized reading' (duration: 403.113414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.755441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.351805Z","time spent":"403.496034ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T22:55:36.755713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.989471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T22:55:36.755944Z","caller":"traceutil/trace.go:171","msg":"trace[1558405234] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1008; }","duration":"748.271927ms","start":"2026-02-18T22:55:36.007654Z","end":"2026-02-18T22:55:36.755926Z","steps":["trace[1558405234] 'agreement among raft nodes before linearized reading' (duration: 743.867612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:36.756353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.007632Z","time spent":"748.710548ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-18T22:55:37.517106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338819128707731,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:37.911992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.362814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:37.912092Z","caller":"traceutil/trace.go:171","msg":"trace[596644896] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.149998335s","start":"2026-02-18T22:55:36.762073Z","end":"2026-02-18T22:55:37.912071Z","steps":["trace[596644896] 'process raft request' (duration: 172.481277ms)","trace[596644896] 'compare' (duration: 977.113738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:37.912147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.762057Z","time spent":"1.150064496s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:38.01786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398338819128707731,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:38.193175Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.258576494s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:55:38.21615Z","caller":"traceutil/trace.go:171","msg":"trace[772420771] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.447817198s","start":"2026-02-18T22:55:36.768312Z","end":"2026-02-18T22:55:38.216129Z","steps":["trace[772420771] 'process raft request' (duration: 1.447778417s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.216255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.768306Z","time spent":"1.447881999s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:38.216714Z","caller":"traceutil/trace.go:171","msg":"trace[1401054387] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.453830369s","start":"2026-02-18T22:55:36.76287Z","end":"2026-02-18T22:55:38.216701Z","steps":["trace[1401054387] 'process raft request' (duration: 1.4530136s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.21678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.762862Z","time spent":"1.453882361s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:38.216908Z","caller":"traceutil/trace.go:171","msg":"trace[701015841] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.451852914s","start":"2026-02-18T22:55:36.765047Z","end":"2026-02-18T22:55:38.2169Z","steps":["trace[701015841] 'process raft request' (duration: 1.450941772s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.216956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.76504Z","time spent":"1.451888934s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:38.217089Z","caller":"traceutil/trace.go:171","msg":"trace[163806426] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.45167059s","start":"2026-02-18T22:55:36.765408Z","end":"2026-02-18T22:55:38.217079Z","steps":["trace[163806426] 'process raft request' (duration: 1.450613684s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.217137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.765402Z","time spent":"1.451708251s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:38.217254Z","caller":"traceutil/trace.go:171","msg":"trace[682061607] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.451187417s","start":"2026-02-18T22:55:36.766059Z","end":"2026-02-18T22:55:38.217246Z","steps":["trace[682061607] 'process raft request' (duration: 1.449994438s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.217299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:36.766052Z","time spent":"1.451221959s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:38.254255Z","caller":"traceutil/trace.go:171","msg":"trace[478733191] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"1.238250915s","start":"2026-02-18T22:55:37.015992Z","end":"2026-02-18T22:55:38.254243Z","steps":["trace[478733191] 'read index received' (duration: 1.203495351s)","trace[478733191] 'applied index is now lower than readState.Index' (duration: 34.754604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:38.254483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2384867s","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:55:38.254624Z","caller":"traceutil/trace.go:171","msg":"trace[1732714902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"1.238652074s","start":"2026-02-18T22:55:37.015959Z","end":"2026-02-18T22:55:38.254612Z","steps":["trace[1732714902] 'agreement among raft nodes before linearized reading' (duration: 1.238345317s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:38.254762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:37.015945Z","time spent":"1.238808088s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:56:20.288457Z","caller":"traceutil/trace.go:171","msg":"trace[2139444122] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"120.129885ms","start":"2026-02-18T22:56:20.168313Z","end":"2026-02-18T22:56:20.288443Z","steps":["trace[2139444122] 'process raft request' (duration: 119.93546ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:34.863263Z","caller":"traceutil/trace.go:171","msg":"trace[1162121802] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"198.561482ms","start":"2026-02-18T22:56:34.66468Z","end":"2026-02-18T22:56:34.863242Z","steps":["trace[1162121802] 'read index received' (duration: 118.176429ms)","trace[1162121802] 'applied index is now lower than readState.Index' (duration: 80.384223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:34.8641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.396582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T22:56:34.864519Z","caller":"traceutil/trace.go:171","msg":"trace[1642382692] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1246; }","duration":"199.842572ms","start":"2026-02-18T22:56:34.664663Z","end":"2026-02-18T22:56:34.864506Z","steps":["trace[1642382692] 'agreement among raft nodes before linearized reading' (duration: 199.268539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:34.865751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.671392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:34.865933Z","caller":"traceutil/trace.go:171","msg":"trace[210007675] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1246; }","duration":"140.843526ms","start":"2026-02-18T22:56:34.725024Z","end":"2026-02-18T22:56:34.865867Z","steps":["trace[210007675] 'agreement among raft nodes before linearized reading' (duration: 140.685872ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:34.864255Z","caller":"traceutil/trace.go:171","msg":"trace[1177205848] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"205.417096ms","start":"2026-02-18T22:56:34.658821Z","end":"2026-02-18T22:56:34.864239Z","steps":["trace[1177205848] 'process raft request' (duration: 124.118131ms)","trace[1177205848] 'compare' (duration: 80.056605ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:38.797469Z","caller":"traceutil/trace.go:171","msg":"trace[70099160] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"112.055014ms","start":"2026-02-18T22:56:38.685393Z","end":"2026-02-18T22:56:38.797449Z","steps":["trace[70099160] 'process raft request' (duration: 68.697156ms)","trace[70099160] 'compare' (duration: 43.112522ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:49.295539Z","caller":"traceutil/trace.go:171","msg":"trace[1081755624] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"265.684549ms","start":"2026-02-18T22:56:49.029835Z","end":"2026-02-18T22:56:49.29552Z","steps":["trace[1081755624] 'read index received' (duration: 263.469278ms)","trace[1081755624] 'applied index is now lower than readState.Index' (duration: 2.214271ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:49.295742Z","caller":"traceutil/trace.go:171","msg":"trace[1590403660] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"278.053148ms","start":"2026-02-18T22:56:49.017676Z","end":"2026-02-18T22:56:49.295729Z","steps":["trace[1590403660] 'process raft request' (duration: 275.729484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.295967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.116969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-18T22:56:49.295999Z","caller":"traceutil/trace.go:171","msg":"trace[2081982288] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1303; }","duration":"266.18034ms","start":"2026-02-18T22:56:49.029809Z","end":"2026-02-18T22:56:49.29599Z","steps":["trace[2081982288] 'agreement among raft nodes before linearized reading' (duration: 266.064427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.296163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.580346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:56:49.296189Z","caller":"traceutil/trace.go:171","msg":"trace[1254070740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"112.646277ms","start":"2026-02-18T22:56:49.183535Z","end":"2026-02-18T22:56:49.296182Z","steps":["trace[1254070740] 'agreement among raft nodes before linearized reading' (duration: 112.572725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:50.952334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.002911ms","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:56:50.952396Z","caller":"traceutil/trace.go:171","msg":"trace[1958892067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1309; }","duration":"106.076142ms","start":"2026-02-18T22:56:50.846306Z","end":"2026-02-18T22:56:50.952382Z","steps":["trace[1958892067] 'range keys from in-memory index tree' (duration: 105.862558ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:53.55359Z","caller":"traceutil/trace.go:171","msg":"trace[611335044] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"117.742266ms","start":"2026-02-18T22:56:53.435817Z","end":"2026-02-18T22:56:53.553559Z","steps":["trace[611335044] 'process raft request' (duration: 49.652243ms)","trace[611335044] 'compare' (duration: 67.790536ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:57.521024Z","caller":"traceutil/trace.go:171","msg":"trace[673915562] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"172.294043ms","start":"2026-02-18T22:56:57.348681Z","end":"2026-02-18T22:56:57.520975Z","steps":["trace[673915562] 'process raft request' (duration: 91.669145ms)","trace[673915562] 'compare' (duration: 80.435753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:47.400542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.859352ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T22:57:47.400698Z","caller":"traceutil/trace.go:171","msg":"trace[109228342] linearizableReadLoop","detail":"{readStateIndex:1601; appliedIndex:1600; }","duration":"278.732864ms","start":"2026-02-18T22:57:47.121941Z","end":"2026-02-18T22:57:47.400674Z","steps":["trace[109228342] 'read index received' (duration: 69.363353ms)","trace[109228342] 'applied index is now lower than readState.Index' (duration: 209.367781ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:57:47.400873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.924068ms","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:57:47.400909Z","caller":"traceutil/trace.go:171","msg":"trace[1156913705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1537; }","duration":"278.98379ms","start":"2026-02-18T22:57:47.121913Z","end":"2026-02-18T22:57:47.400897Z","steps":["trace[1156913705] 'agreement among raft nodes before linearized reading' (duration: 278.825516ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:47.511117Z","caller":"traceutil/trace.go:171","msg":"trace[347292842] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"105.399026ms","start":"2026-02-18T22:57:47.4057Z","end":"2026-02-18T22:57:47.511099Z","steps":["trace[347292842] 'process raft request' (duration: 105.283714ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:47.537203Z","caller":"traceutil/trace.go:171","msg":"trace[1881101536] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"129.840999ms","start":"2026-02-18T22:57:47.407342Z","end":"2026-02-18T22:57:47.537183Z","steps":["trace[1881101536] 'process raft request' (duration: 129.343148ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:47.654487Z","caller":"traceutil/trace.go:171","msg":"trace[418779803] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"113.451245ms","start":"2026-02-18T22:57:47.540772Z","end":"2026-02-18T22:57:47.654223Z","steps":["trace[418779803] 'process raft request' (duration: 55.576421ms)","trace[418779803] 'compare' (duration: 57.755542ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:58:38.008947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.539309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-18T22:58:38.008997Z","caller":"traceutil/trace.go:171","msg":"trace[637219618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1744; }","duration":"102.617752ms","start":"2026-02-18T22:58:37.90637Z","end":"2026-02-18T22:58:38.008988Z","steps":["trace[637219618] 'range keys from in-memory index tree' (duration: 102.445257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:38.0091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.494493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T22:58:38.009116Z","caller":"traceutil/trace.go:171","msg":"trace[1659741695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1744; }","duration":"203.535714ms","start":"2026-02-18T22:58:37.805575Z","end":"2026-02-18T22:58:38.00911Z","steps":["trace[1659741695] 'range keys from in-memory index tree' (duration: 203.425141ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:58:49.078709Z","caller":"traceutil/trace.go:171","msg":"trace[380563043] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"291.651936ms","start":"2026-02-18T22:58:48.787035Z","end":"2026-02-18T22:58:49.078687Z","steps":["trace[380563043] 'read index received' (duration: 291.471441ms)","trace[380563043] 'applied index is now lower than readState.Index' (duration: 179.445µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:58:49.079347Z","caller":"traceutil/trace.go:171","msg":"trace[1553793512] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"355.788818ms","start":"2026-02-18T22:58:48.723499Z","end":"2026-02-18T22:58:49.079288Z","steps":["trace[1553793512] 'process raft request' (duration: 355.078711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:49.079581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:48.723482Z","time spent":"355.966431ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:58:49.079709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.652619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-18T22:58:49.079752Z","caller":"traceutil/trace.go:171","msg":"trace[739901279] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1814; }","duration":"292.7101ms","start":"2026-02-18T22:58:48.787031Z","end":"2026-02-18T22:58:49.079741Z","steps":["trace[739901279] 'agreement among raft nodes before linearized reading' (duration: 291.743678ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:12.527993Z","caller":"traceutil/trace.go:171","msg":"trace[744083242] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"134.460942ms","start":"2026-02-18T22:59:12.393514Z","end":"2026-02-18T22:59:12.527975Z","steps":["trace[744083242] 'process raft request' (duration: 133.139051ms)"],"step_count":1}