{"level":"warn","ts":"2026-02-25T10:03:09.18301Z","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-25T10:03:09.183134Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T10:03:09.183215Z","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-25T10:03:09.183229Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-25T10:03:09.183258Z","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-25T10:03:09.18392Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-25T10:03:09.183996Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T10:03:09.187713Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.495038ms"} {"level":"info","ts":"2026-02-25T10:03:09.19252Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-25T10:03:09.192618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T10:03:09.192643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-25T10:03:09.192651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T10:03:09.19266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-25T10:03:09.192696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-25T10:03:09.19914Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T10:03:09.200511Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T10:03:09.201447Z","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-25T10:03:09.203038Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T10:03:09.210522Z","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-25T10:03:09.210653Z","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-25T10:03:09.210683Z","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-25T10:03:09.210548Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T10:03:09.210939Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-25T10:03:09.210903Z","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-25T10:03:09.211023Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-25T10:03:09.211324Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T10:03:09.211341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-25T10:03:09.211371Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T10:03:09.211481Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-25T10:03:09.79389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T10:03:09.794017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T10:03:09.794062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-25T10:03:09.794084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-25T10:03:09.794095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-25T10:03:09.794111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-25T10:03:09.794124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-25T10:03:09.795194Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T10:03:09.795233Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:03:09.795204Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:03:09.795487Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T10:03:09.79552Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T10:03:09.798228Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-25T10:03:09.802094Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T10:03:09.812772Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:03:09.814559Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:03:09.814708Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:03:09.814737Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T10:03:19.203166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.438722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:03:19.203328Z","caller":"traceutil/trace.go:171","msg":"trace[477543412] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"996.384806ms","start":"2026-02-25T10:03:18.206926Z","end":"2026-02-25T10:03:19.203311Z","steps":["trace[477543412] 'process raft request' (duration: 28.358092ms)","trace[477543412] 'compare' (duration: 967.311259ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:19.203414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.206908Z","time spent":"996.483489ms","remote":"127.0.0.1:46682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:19.541645Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.306301368s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:03:19.546901Z","caller":"traceutil/trace.go:171","msg":"trace[1491293986] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:357; }","duration":"1.33914794s","start":"2026-02-25T10:03:18.207704Z","end":"2026-02-25T10:03:19.546852Z","steps":["trace[1491293986] 'read index received' (duration: 27.707477ms)","trace[1491293986] 'applied index is now lower than readState.Index' (duration: 1.311438763s)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:19.547406Z","caller":"traceutil/trace.go:171","msg":"trace[148877774] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"1.340264985s","start":"2026-02-25T10:03:18.207127Z","end":"2026-02-25T10:03:19.547392Z","steps":["trace[148877774] 'process raft request' (duration: 1.33467733s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.547563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.207097Z","time spent":"1.340355277s","remote":"127.0.0.1:46682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:19.54787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340162853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T10:03:19.547912Z","caller":"traceutil/trace.go:171","msg":"trace[1328045990] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:347; }","duration":"1.340225154s","start":"2026-02-25T10:03:18.207673Z","end":"2026-02-25T10:03:19.547898Z","steps":["trace[1328045990] 'agreement among raft nodes before linearized reading' (duration: 1.340138142s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.547948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.207665Z","time spent":"1.340273875s","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-25T10:03:19.548287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293472141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T10:03:19.548388Z","caller":"traceutil/trace.go:171","msg":"trace[441450875] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:347; }","duration":"1.293574604s","start":"2026-02-25T10:03:18.2548Z","end":"2026-02-25T10:03:19.548375Z","steps":["trace[441450875] 'agreement among raft nodes before linearized reading' (duration: 1.29342848s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.548426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.254742Z","time spent":"1.293675567s","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-25T10:03:19.548816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.865996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:03:19.548865Z","caller":"traceutil/trace.go:171","msg":"trace[2027132990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:347; }","duration":"164.954278ms","start":"2026-02-25T10:03:19.383897Z","end":"2026-02-25T10:03:19.548851Z","steps":["trace[2027132990] 'agreement among raft nodes before linearized reading' (duration: 164.851656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.549068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.909362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T10:03:19.549099Z","caller":"traceutil/trace.go:171","msg":"trace[1833240127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:347; }","duration":"993.980754ms","start":"2026-02-25T10:03:18.555107Z","end":"2026-02-25T10:03:19.549088Z","steps":["trace[1833240127] 'agreement among raft nodes before linearized reading' (duration: 993.916963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.549134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.555088Z","time spent":"994.037015ms","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-25T10:03:19.549303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043336056s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T10:03:19.549334Z","caller":"traceutil/trace.go:171","msg":"trace[218209391] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:347; }","duration":"1.043402346s","start":"2026-02-25T10:03:18.505922Z","end":"2026-02-25T10:03:19.549324Z","steps":["trace[218209391] 'agreement among raft nodes before linearized reading' (duration: 1.043340715s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.549362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.505903Z","time spent":"1.043451878s","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-25T10:03:19.549502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094866384s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T10:03:19.549538Z","caller":"traceutil/trace.go:171","msg":"trace[405789183] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:347; }","duration":"1.094925546s","start":"2026-02-25T10:03:18.4546Z","end":"2026-02-25T10:03:19.549525Z","steps":["trace[405789183] 'agreement among raft nodes before linearized reading' (duration: 1.094868614s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.549572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.45457Z","time spent":"1.094992867s","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-25T10:03:19.549733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145444221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-25T10:03:19.549762Z","caller":"traceutil/trace.go:171","msg":"trace[1950217448] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:347; }","duration":"1.145495273s","start":"2026-02-25T10:03:18.404257Z","end":"2026-02-25T10:03:19.549752Z","steps":["trace[1950217448] 'agreement among raft nodes before linearized reading' (duration: 1.145437921s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.54979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.404245Z","time spent":"1.145537793s","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-25T10:03:19.54993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194173097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-25T10:03:19.549959Z","caller":"traceutil/trace.go:171","msg":"trace[1355317546] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:347; }","duration":"1.194230919s","start":"2026-02-25T10:03:18.35572Z","end":"2026-02-25T10:03:19.54995Z","steps":["trace[1355317546] 'agreement among raft nodes before linearized reading' (duration: 1.194177188s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.549985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.355701Z","time spent":"1.19427798s","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-25T10:03:19.550111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244409758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T10:03:19.55014Z","caller":"traceutil/trace.go:171","msg":"trace[1497386577] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:347; }","duration":"1.2444918s","start":"2026-02-25T10:03:18.30564Z","end":"2026-02-25T10:03:19.550132Z","steps":["trace[1497386577] 'agreement among raft nodes before linearized reading' (duration: 1.244443549s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.550166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:18.305619Z","time spent":"1.244541261s","remote":"127.0.0.1:46518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-02-25T10:03:19.699701Z","caller":"traceutil/trace.go:171","msg":"trace[1296405776] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"109.633105ms","start":"2026-02-25T10:03:19.590046Z","end":"2026-02-25T10:03:19.699679Z","steps":["trace[1296405776] 'process raft request' (duration: 102.777772ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:19.700048Z","caller":"traceutil/trace.go:171","msg":"trace[188174190] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"109.82832ms","start":"2026-02-25T10:03:19.590155Z","end":"2026-02-25T10:03:19.699983Z","steps":["trace[188174190] 'process raft request' (duration: 109.481192ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:19.700296Z","caller":"traceutil/trace.go:171","msg":"trace[241973314] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:371; }","duration":"106.977205ms","start":"2026-02-25T10:03:19.593305Z","end":"2026-02-25T10:03:19.700282Z","steps":["trace[241973314] 'read index received' (duration: 7.402945ms)","trace[241973314] 'applied index is now lower than readState.Index' (duration: 99.5736ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:19.700293Z","caller":"traceutil/trace.go:171","msg":"trace[1885253568] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"109.049281ms","start":"2026-02-25T10:03:19.59122Z","end":"2026-02-25T10:03:19.700269Z","steps":["trace[1885253568] 'process raft request' (duration: 108.880847ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:19.700373Z","caller":"traceutil/trace.go:171","msg":"trace[880946647] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"105.642735ms","start":"2026-02-25T10:03:19.594722Z","end":"2026-02-25T10:03:19.700365Z","steps":["trace[880946647] 'process raft request' (duration: 105.523963ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:19.7003Z","caller":"traceutil/trace.go:171","msg":"trace[1168486878] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"109.325397ms","start":"2026-02-25T10:03:19.590899Z","end":"2026-02-25T10:03:19.700225Z","steps":["trace[1168486878] 'process raft request' (duration: 108.977079ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:19.700468Z","caller":"traceutil/trace.go:171","msg":"trace[1895697301] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"106.221848ms","start":"2026-02-25T10:03:19.59422Z","end":"2026-02-25T10:03:19.700442Z","steps":["trace[1895697301] 'process raft request' (duration: 105.997143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:19.705458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.060562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T10:03:19.709304Z","caller":"traceutil/trace.go:171","msg":"trace[702969147] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:366; }","duration":"116.013776ms","start":"2026-02-25T10:03:19.593263Z","end":"2026-02-25T10:03:19.709277Z","steps":["trace[702969147] 'agreement among raft nodes before linearized reading' (duration: 107.23011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:25.533033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731958149,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:03:25.803206Z","caller":"traceutil/trace.go:171","msg":"trace[1707343024] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"915.39539ms","start":"2026-02-25T10:03:24.887742Z","end":"2026-02-25T10:03:25.803138Z","steps":["trace[1707343024] 'process raft request' (duration: 915.272797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:25.803497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:24.887718Z","time spent":"915.609205ms","remote":"127.0.0.1:46498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:25.96121Z","caller":"traceutil/trace.go:171","msg":"trace[2053929055] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:422; }","duration":"928.563645ms","start":"2026-02-25T10:03:25.032586Z","end":"2026-02-25T10:03:25.96115Z","steps":["trace[2053929055] 'read index received' (duration: 770.523974ms)","trace[2053929055] 'applied index is now lower than readState.Index' (duration: 157.930419ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:25.961327Z","caller":"traceutil/trace.go:171","msg":"trace[1204337325] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.014066811s","start":"2026-02-25T10:03:24.947178Z","end":"2026-02-25T10:03:25.961245Z","steps":["trace[1204337325] 'process raft request' (duration: 1.013411106s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:25.961636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:24.947155Z","time spent":"1.014369528s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:25.962374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.408884ms","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-25T10:03:25.962468Z","caller":"traceutil/trace.go:171","msg":"trace[1563337986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"152.504886ms","start":"2026-02-25T10:03:25.809916Z","end":"2026-02-25T10:03:25.962421Z","steps":["trace[1563337986] 'agreement among raft nodes before linearized reading' (duration: 152.285631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:25.962601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.598098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:03:25.962726Z","caller":"traceutil/trace.go:171","msg":"trace[1355868576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"368.779162ms","start":"2026-02-25T10:03:25.593906Z","end":"2026-02-25T10:03:25.962685Z","steps":["trace[1355868576] 'agreement among raft nodes before linearized reading' (duration: 367.728019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:25.962822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:25.593886Z","time spent":"368.919005ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:03:25.962207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.617599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:25.96348Z","caller":"traceutil/trace.go:171","msg":"trace[1414559048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"930.931729ms","start":"2026-02-25T10:03:25.03252Z","end":"2026-02-25T10:03:25.963452Z","steps":["trace[1414559048] 'agreement among raft nodes before linearized reading' (duration: 928.978465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:25.963546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:25.032498Z","time spent":"931.033911ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:03:32.492988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731958225,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:03:32.993375Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731958225,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:03:33.041493Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.116050196s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:03:33.041866Z","caller":"traceutil/trace.go:171","msg":"trace[430712777] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:469; }","duration":"1.049883834s","start":"2026-02-25T10:03:31.991964Z","end":"2026-02-25T10:03:33.041848Z","steps":["trace[430712777] 'read index received' (duration: 1.049720101s)","trace[430712777] 'applied index is now lower than readState.Index' (duration: 162.663µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:33.042005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050019617s","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-25T10:03:33.042033Z","caller":"traceutil/trace.go:171","msg":"trace[739649823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:455; }","duration":"1.050104249s","start":"2026-02-25T10:03:31.991918Z","end":"2026-02-25T10:03:33.042023Z","steps":["trace[739649823] 'agreement among raft nodes before linearized reading' (duration: 1.050038878s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:33.042055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:31.991899Z","time spent":"1.05015153s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T10:03:33.042017Z","caller":"traceutil/trace.go:171","msg":"trace[127155436] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"1.116629489s","start":"2026-02-25T10:03:31.925285Z","end":"2026-02-25T10:03:33.041914Z","steps":["trace[127155436] 'process raft request' (duration: 1.116405774s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:33.042217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00866162s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:03:33.042199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.137964ms","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-25T10:03:33.042241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.786449ms","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-25T10:03:33.04228Z","caller":"traceutil/trace.go:171","msg":"trace[462370014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"1.008757393s","start":"2026-02-25T10:03:32.033509Z","end":"2026-02-25T10:03:33.042266Z","steps":["trace[462370014] 'agreement among raft nodes before linearized reading' (duration: 1.008665001s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:33.042288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.461003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T10:03:33.042311Z","caller":"traceutil/trace.go:171","msg":"trace[1188642401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:455; }","duration":"703.285918ms","start":"2026-02-25T10:03:32.338995Z","end":"2026-02-25T10:03:33.042281Z","steps":["trace[1188642401] 'agreement among raft nodes before linearized reading' (duration: 703.060573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:33.04232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:32.033456Z","time spent":"1.008856745s","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:03:33.042338Z","caller":"traceutil/trace.go:171","msg":"trace[1127722486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"703.951452ms","start":"2026-02-25T10:03:32.338355Z","end":"2026-02-25T10:03:33.042306Z","steps":["trace[1127722486] 'agreement among raft nodes before linearized reading' (duration: 703.758828ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:33.042353Z","caller":"traceutil/trace.go:171","msg":"trace[190587892] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:455; }","duration":"126.544085ms","start":"2026-02-25T10:03:32.915783Z","end":"2026-02-25T10:03:33.042327Z","steps":["trace[190587892] 'agreement among raft nodes before linearized reading' (duration: 126.458133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:33.042371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:32.338984Z","time spent":"703.37713ms","remote":"127.0.0.1:46572","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-25T10:03:33.042148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04837598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T10:03:33.042399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:32.338331Z","time spent":"704.058314ms","remote":"127.0.0.1:46572","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":"info","ts":"2026-02-25T10:03:33.042415Z","caller":"traceutil/trace.go:171","msg":"trace[1379929391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:455; }","duration":"1.048699897s","start":"2026-02-25T10:03:31.993707Z","end":"2026-02-25T10:03:33.042407Z","steps":["trace[1379929391] 'agreement among raft nodes before linearized reading' (duration: 1.048319888s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:33.042455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:31.993696Z","time spent":"1.048735947s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:03:33.042575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:31.925261Z","time spent":"1.116991237s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:47.665216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731958378,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:03:47.995986Z","caller":"traceutil/trace.go:171","msg":"trace[1580021042] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"832.694957ms","start":"2026-02-25T10:03:47.163268Z","end":"2026-02-25T10:03:47.995963Z","steps":["trace[1580021042] 'process raft request' (duration: 832.533064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:47.996145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:47.163252Z","time spent":"832.810819ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:48.020449Z","caller":"traceutil/trace.go:171","msg":"trace[262912746] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"855.691383ms","start":"2026-02-25T10:03:47.164726Z","end":"2026-02-25T10:03:48.020417Z","steps":["trace[262912746] 'read index received' (duration: 831.119512ms)","trace[262912746] 'applied index is now lower than readState.Index' (duration: 24.57038ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:48.020542Z","caller":"traceutil/trace.go:171","msg":"trace[501792721] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"855.57193ms","start":"2026-02-25T10:03:47.164951Z","end":"2026-02-25T10:03:48.020523Z","steps":["trace[501792721] 'process raft request' (duration: 855.308194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:48.020658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:47.164938Z","time spent":"855.641771ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:48.020646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.893387ms","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-25T10:03:48.020725Z","caller":"traceutil/trace.go:171","msg":"trace[1316155596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"856.006579ms","start":"2026-02-25T10:03:47.1647Z","end":"2026-02-25T10:03:48.020706Z","steps":["trace[1316155596] 'agreement among raft nodes before linearized reading' (duration: 855.842135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:48.020769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:47.164689Z","time spent":"856.07237ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:03:48.02067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.076174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-25T10:03:48.020878Z","caller":"traceutil/trace.go:171","msg":"trace[749182911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"853.282638ms","start":"2026-02-25T10:03:47.167564Z","end":"2026-02-25T10:03:48.020847Z","steps":["trace[749182911] 'agreement among raft nodes before linearized reading' (duration: 852.991042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:48.020968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:47.167555Z","time spent":"853.38171ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:03:53.45905Z","caller":"traceutil/trace.go:171","msg":"trace[226415800] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"427.265244ms","start":"2026-02-25T10:03:53.031765Z","end":"2026-02-25T10:03:53.45903Z","steps":["trace[226415800] 'read index received' (duration: 427.062379ms)","trace[226415800] 'applied index is now lower than readState.Index' (duration: 202.095µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:53.459229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.449438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:53.459261Z","caller":"traceutil/trace.go:171","msg":"trace[784733329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"427.5253ms","start":"2026-02-25T10:03:53.031727Z","end":"2026-02-25T10:03:53.459252Z","steps":["trace[784733329] 'agreement among raft nodes before linearized reading' (duration: 427.383187ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:53.459218Z","caller":"traceutil/trace.go:171","msg":"trace[1655408512] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"437.434632ms","start":"2026-02-25T10:03:53.021723Z","end":"2026-02-25T10:03:53.459158Z","steps":["trace[1655408512] 'process raft request' (duration: 437.177276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:53.459288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:53.031713Z","time spent":"427.568821ms","remote":"127.0.0.1:46290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:03:53.45948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:53.021684Z","time spent":"437.656337ms","remote":"127.0.0.1:46498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:55.452008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112230661s","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-25T10:03:55.452131Z","caller":"traceutil/trace.go:171","msg":"trace[1154398304] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:542; }","duration":"1.112373704s","start":"2026-02-25T10:03:54.339742Z","end":"2026-02-25T10:03:55.452116Z","steps":["trace[1154398304] 'range keys from in-memory index tree' (duration: 1.11220897s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:55.474593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068810919s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:03:55.474821Z","caller":"traceutil/trace.go:171","msg":"trace[1250598292] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.404922429s","start":"2026-02-25T10:03:54.069868Z","end":"2026-02-25T10:03:55.474791Z","steps":["trace[1250598292] 'process raft request' (duration: 335.762313ms)","trace[1250598292] 'compare' (duration: 1.046416506s)","trace[1250598292] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:418; } (duration: 22.242869ms)"],"step_count":3} {"level":"warn","ts":"2026-02-25T10:03:55.474896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:54.069852Z","time spent":"1.405016801s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:55.475148Z","caller":"traceutil/trace.go:171","msg":"trace[155877815] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.404300824s","start":"2026-02-25T10:03:54.070838Z","end":"2026-02-25T10:03:55.475138Z","steps":["trace[155877815] 'process raft request' (duration: 1.404256054s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:55.475201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:54.070828Z","time spent":"1.404346156s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:55.475346Z","caller":"traceutil/trace.go:171","msg":"trace[284718376] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.404500539s","start":"2026-02-25T10:03:54.070837Z","end":"2026-02-25T10:03:55.475338Z","steps":["trace[284718376] 'process raft request' (duration: 1.403882575s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:55.475385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:54.070831Z","time spent":"1.404533779s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:56.511894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.713711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:03:56.511936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.13051ms","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-25T10:03:56.511987Z","caller":"traceutil/trace.go:171","msg":"trace[845764037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"422.842375ms","start":"2026-02-25T10:03:56.089124Z","end":"2026-02-25T10:03:56.511966Z","steps":["trace[845764037] 'range keys from in-memory index tree' (duration: 422.593619ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:56.512044Z","caller":"traceutil/trace.go:171","msg":"trace[256474597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"348.308664ms","start":"2026-02-25T10:03:56.163704Z","end":"2026-02-25T10:03:56.512013Z","steps":["trace[256474597] 'range keys from in-memory index tree' (duration: 347.891155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:56.512103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:56.163671Z","time spent":"348.420696ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:03:56.512114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:56.08911Z","time spent":"422.935426ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:04:08.935528Z","caller":"traceutil/trace.go:171","msg":"trace[2104687167] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"112.312627ms","start":"2026-02-25T10:04:08.823192Z","end":"2026-02-25T10:04:08.935505Z","steps":["trace[2104687167] 'read index received' (duration: 112.066891ms)","trace[2104687167] 'applied index is now lower than readState.Index' (duration: 244.906µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:08.935598Z","caller":"traceutil/trace.go:171","msg":"trace[2134781603] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"248.804525ms","start":"2026-02-25T10:04:08.686746Z","end":"2026-02-25T10:04:08.935551Z","steps":["trace[2134781603] 'process raft request' (duration: 248.619901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:08.935708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.506081ms","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-25T10:04:08.935737Z","caller":"traceutil/trace.go:171","msg":"trace[762270909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:703; }","duration":"112.587643ms","start":"2026-02-25T10:04:08.82314Z","end":"2026-02-25T10:04:08.935728Z","steps":["trace[762270909] 'agreement among raft nodes before linearized reading' (duration: 112.45375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:15.466809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731958779,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:04:15.606035Z","caller":"traceutil/trace.go:171","msg":"trace[1123992893] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"640.348947ms","start":"2026-02-25T10:04:14.965659Z","end":"2026-02-25T10:04:15.606007Z","steps":["trace[1123992893] 'read index received' (duration: 640.128812ms)","trace[1123992893] 'applied index is now lower than readState.Index' (duration: 218.755µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:15.6061Z","caller":"traceutil/trace.go:171","msg":"trace[1418561820] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"648.09225ms","start":"2026-02-25T10:04:14.957978Z","end":"2026-02-25T10:04:15.60607Z","steps":["trace[1418561820] 'process raft request' (duration: 647.871675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:15.606266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:14.95796Z","time spent":"648.214533ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:15.606363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.669094ms","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-25T10:04:15.606491Z","caller":"traceutil/trace.go:171","msg":"trace[1752774182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"640.844198ms","start":"2026-02-25T10:04:14.965626Z","end":"2026-02-25T10:04:15.60647Z","steps":["trace[1752774182] 'agreement among raft nodes before linearized reading' (duration: 640.531491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:15.606542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:14.965614Z","time spent":"640.917309ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T10:04:16.380712Z","caller":"traceutil/trace.go:171","msg":"trace[530574946] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"769.775429ms","start":"2026-02-25T10:04:15.610911Z","end":"2026-02-25T10:04:16.380686Z","steps":["trace[530574946] 'process raft request' (duration: 767.401605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:16.380867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:15.610888Z","time spent":"769.895271ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:17.244779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.577997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:04:17.244936Z","caller":"traceutil/trace.go:171","msg":"trace[1864809507] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"1.633866097s","start":"2026-02-25T10:04:15.61105Z","end":"2026-02-25T10:04:17.244916Z","steps":["trace[1864809507] 'process raft request' (duration: 1.498062285s)","trace[1864809507] 'compare' (duration: 135.445705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:17.245028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:15.611036Z","time spent":"1.63394666s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:04:17.245166Z","caller":"traceutil/trace.go:171","msg":"trace[1609542116] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"1.634033652s","start":"2026-02-25T10:04:15.611122Z","end":"2026-02-25T10:04:17.245156Z","steps":["trace[1609542116] 'process raft request' (duration: 1.633869318s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:17.245236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:15.6111Z","time spent":"1.634108473s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:04:17.24524Z","caller":"traceutil/trace.go:171","msg":"trace[313284988] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"1.633623783s","start":"2026-02-25T10:04:15.611587Z","end":"2026-02-25T10:04:17.245211Z","steps":["trace[313284988] 'process raft request' (duration: 1.63350895s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:17.245503Z","caller":"traceutil/trace.go:171","msg":"trace[1138103127] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:753; }","duration":"1.214178725s","start":"2026-02-25T10:04:16.031297Z","end":"2026-02-25T10:04:17.245476Z","steps":["trace[1138103127] 'read index received' (duration: 347.030226ms)","trace[1138103127] 'applied index is now lower than readState.Index' (duration: 867.147319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:17.245566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:15.61158Z","time spent":"1.63390648s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:04:17.245607Z","caller":"traceutil/trace.go:171","msg":"trace[1030150775] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"1.228591027s","start":"2026-02-25T10:04:16.017006Z","end":"2026-02-25T10:04:17.245597Z","steps":["trace[1030150775] 'process raft request' (duration: 1.228136446s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:17.245669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214357158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:04:17.245723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.016989Z","time spent":"1.228645999s","remote":"127.0.0.1:46482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:04:17.24573Z","caller":"traceutil/trace.go:171","msg":"trace[178672554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"1.214463391s","start":"2026-02-25T10:04:16.031246Z","end":"2026-02-25T10:04:17.245709Z","steps":["trace[178672554] 'agreement among raft nodes before linearized reading' (duration: 1.214363199s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:17.245776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.031226Z","time spent":"1.214542473s","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:17.294145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214023161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:04:17.294174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.216916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:04:17.294164Z","caller":"traceutil/trace.go:171","msg":"trace[1811233637] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"107.58625ms","start":"2026-02-25T10:04:17.186532Z","end":"2026-02-25T10:04:17.294118Z","steps":["trace[1811233637] 'process raft request' (duration: 107.361156ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:17.294237Z","caller":"traceutil/trace.go:171","msg":"trace[601443973] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:732; }","duration":"470.330519ms","start":"2026-02-25T10:04:16.823894Z","end":"2026-02-25T10:04:17.294224Z","steps":["trace[601443973] 'agreement among raft nodes before linearized reading' (duration: 470.160515ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:17.29424Z","caller":"traceutil/trace.go:171","msg":"trace[407467120] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:732; }","duration":"1.214175885s","start":"2026-02-25T10:04:16.080041Z","end":"2026-02-25T10:04:17.294217Z","steps":["trace[407467120] 'agreement among raft nodes before linearized reading' (duration: 1.214006041s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:17.294265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.823873Z","time spent":"470.38239ms","remote":"127.0.0.1:46570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:17.294288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:16.080023Z","time spent":"1.214254606s","remote":"127.0.0.1:46794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:04:17.965195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.15262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:17.965263Z","caller":"traceutil/trace.go:171","msg":"trace[426819176] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:733; }","duration":"103.243972ms","start":"2026-02-25T10:04:17.862Z","end":"2026-02-25T10:04:17.965244Z","steps":["trace[426819176] 'count revisions from in-memory index tree' (duration: 103.114189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:17.965444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.09395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:04:17.965435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.445107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:17.965483Z","caller":"traceutil/trace.go:171","msg":"trace[915327864] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:733; }","duration":"103.138951ms","start":"2026-02-25T10:04:17.862333Z","end":"2026-02-25T10:04:17.965472Z","steps":["trace[915327864] 'range keys from in-memory index tree' (duration: 103.064539ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:17.965516Z","caller":"traceutil/trace.go:171","msg":"trace[60502069] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:733; }","duration":"103.54275ms","start":"2026-02-25T10:04:17.86195Z","end":"2026-02-25T10:04:17.965493Z","steps":["trace[60502069] 'range keys from in-memory index tree' (duration: 103.332215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:18.885478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731958806,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:19.298862Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.00775565s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T10:04:19.471668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.536396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T10:04:19.471872Z","caller":"traceutil/trace.go:171","msg":"trace[859026273] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"1.08722554s","start":"2026-02-25T10:04:18.384622Z","end":"2026-02-25T10:04:19.471847Z","steps":["trace[859026273] 'read index received' (duration: 914.381748ms)","trace[859026273] 'applied index is now lower than readState.Index' (duration: 172.841532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:19.47212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.706807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T10:04:19.472141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087494786s","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-25T10:04:19.472216Z","caller":"traceutil/trace.go:171","msg":"trace[2021056338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"1.087610419s","start":"2026-02-25T10:04:18.384587Z","end":"2026-02-25T10:04:19.472197Z","steps":["trace[2021056338] 'agreement among raft nodes before linearized reading' (duration: 1.087368144s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:19.472226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.594254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T10:04:19.472257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:18.384573Z","time spent":"1.08767655s","remote":"127.0.0.1:46572","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-25T10:04:19.472267Z","caller":"traceutil/trace.go:171","msg":"trace[164216619] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:733; }","duration":"192.671446ms","start":"2026-02-25T10:04:19.279582Z","end":"2026-02-25T10:04:19.472253Z","steps":["trace[164216619] 'agreement among raft nodes before linearized reading' (duration: 192.605185ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:19.472165Z","caller":"traceutil/trace.go:171","msg":"trace[1471771898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"221.766459ms","start":"2026-02-25T10:04:19.250386Z","end":"2026-02-25T10:04:19.472152Z","steps":["trace[1471771898] 'agreement among raft nodes before linearized reading' (duration: 221.604905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:19.472405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.821002ms","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-25T10:04:19.472462Z","caller":"traceutil/trace.go:171","msg":"trace[340573572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"222.902104ms","start":"2026-02-25T10:04:19.249542Z","end":"2026-02-25T10:04:19.472444Z","steps":["trace[340573572] 'agreement among raft nodes before linearized reading' (duration: 222.73795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:19.472596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.082018ms","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-25T10:04:19.472653Z","caller":"traceutil/trace.go:171","msg":"trace[2011427533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:733; }","duration":"223.189071ms","start":"2026-02-25T10:04:19.249448Z","end":"2026-02-25T10:04:19.472637Z","steps":["trace[2011427533] 'agreement among raft nodes before linearized reading' (duration: 223.024547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:19.472037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.637405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:19.472957Z","caller":"traceutil/trace.go:171","msg":"trace[495001867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"442.586326ms","start":"2026-02-25T10:04:19.030355Z","end":"2026-02-25T10:04:19.472941Z","steps":["trace[495001867] 'agreement among raft nodes before linearized reading' (duration: 441.617934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:19.473001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:19.03032Z","time spent":"442.667268ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:04:19.801502Z","caller":"traceutil/trace.go:171","msg":"trace[827830915] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"142.190746ms","start":"2026-02-25T10:04:19.65929Z","end":"2026-02-25T10:04:19.801481Z","steps":["trace[827830915] 'process raft request' (duration: 142.073274ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:26.344579Z","caller":"traceutil/trace.go:171","msg":"trace[1341670617] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"311.197272ms","start":"2026-02-25T10:04:26.033357Z","end":"2026-02-25T10:04:26.344554Z","steps":["trace[1341670617] 'read index received' (duration: 310.991097ms)","trace[1341670617] 'applied index is now lower than readState.Index' (duration: 205.035µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:26.344686Z","caller":"traceutil/trace.go:171","msg":"trace[1335685648] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"507.212855ms","start":"2026-02-25T10:04:25.837436Z","end":"2026-02-25T10:04:26.344649Z","steps":["trace[1335685648] 'process raft request' (duration: 506.95857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:26.344765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.253536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T10:04:26.344784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.984908ms","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-25T10:04:26.344875Z","caller":"traceutil/trace.go:171","msg":"trace[263514792] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:760; }","duration":"294.10689ms","start":"2026-02-25T10:04:26.050758Z","end":"2026-02-25T10:04:26.344865Z","steps":["trace[263514792] 'agreement among raft nodes before linearized reading' (duration: 293.992747ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:26.344878Z","caller":"traceutil/trace.go:171","msg":"trace[2069277354] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:760; }","duration":"299.393758ms","start":"2026-02-25T10:04:26.045442Z","end":"2026-02-25T10:04:26.344836Z","steps":["trace[2069277354] 'agreement among raft nodes before linearized reading' (duration: 299.251495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:26.344904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:25.837415Z","time spent":"507.352338ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:26.345343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.363877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:26.345373Z","caller":"traceutil/trace.go:171","msg":"trace[2104167385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"312.061722ms","start":"2026-02-25T10:04:26.033302Z","end":"2026-02-25T10:04:26.345364Z","steps":["trace[2104167385] 'agreement among raft nodes before linearized reading' (duration: 311.380187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:26.345395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:26.033278Z","time spent":"312.111242ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:04:41.660673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.215193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:41.660755Z","caller":"traceutil/trace.go:171","msg":"trace[1174519444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"627.369496ms","start":"2026-02-25T10:04:41.033368Z","end":"2026-02-25T10:04:41.660738Z","steps":["trace[1174519444] 'range keys from in-memory index tree' (duration: 627.140852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:41.660793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:41.033351Z","time spent":"627.431918ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:04:42.094602Z","caller":"traceutil/trace.go:171","msg":"trace[1138578995] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"311.102535ms","start":"2026-02-25T10:04:41.783466Z","end":"2026-02-25T10:04:42.094569Z","steps":["trace[1138578995] 'process raft request' (duration: 310.825429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:42.095051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:41.783457Z","time spent":"311.254438ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:04:42.095341Z","caller":"traceutil/trace.go:171","msg":"trace[1847256662] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"309.56381ms","start":"2026-02-25T10:04:41.78576Z","end":"2026-02-25T10:04:42.095323Z","steps":["trace[1847256662] 'process raft request' (duration: 308.760982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:42.095421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:41.785747Z","time spent":"309.635751ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:43.52124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.319605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:43.521428Z","caller":"traceutil/trace.go:171","msg":"trace[2105696157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"488.53275ms","start":"2026-02-25T10:04:43.032806Z","end":"2026-02-25T10:04:43.521339Z","steps":["trace[2105696157] 'range keys from in-memory index tree' (duration: 488.220913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:43.521493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:43.032785Z","time spent":"488.693094ms","remote":"127.0.0.1:46290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:04:43.937281Z","caller":"traceutil/trace.go:171","msg":"trace[1944502984] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"137.643819ms","start":"2026-02-25T10:04:43.799616Z","end":"2026-02-25T10:04:43.937259Z","steps":["trace[1944502984] 'read index received' (duration: 137.474845ms)","trace[1944502984] 'applied index is now lower than readState.Index' (duration: 168.113µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:43.937541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.903985ms","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-25T10:04:43.937584Z","caller":"traceutil/trace.go:171","msg":"trace[1542688637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"137.963436ms","start":"2026-02-25T10:04:43.79961Z","end":"2026-02-25T10:04:43.937573Z","steps":["trace[1542688637] 'agreement among raft nodes before linearized reading' (duration: 137.754792ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:43.937767Z","caller":"traceutil/trace.go:171","msg":"trace[1383510291] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"153.395955ms","start":"2026-02-25T10:04:43.784319Z","end":"2026-02-25T10:04:43.937715Z","steps":["trace[1383510291] 'process raft request' (duration: 152.822012ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:44.202911Z","caller":"traceutil/trace.go:171","msg":"trace[2043569795] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"169.720841ms","start":"2026-02-25T10:04:44.033164Z","end":"2026-02-25T10:04:44.202885Z","steps":["trace[2043569795] 'read index received' (duration: 136.503384ms)","trace[2043569795] 'applied index is now lower than readState.Index' (duration: 33.216117ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:44.202951Z","caller":"traceutil/trace.go:171","msg":"trace[1833874944] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"260.484185ms","start":"2026-02-25T10:04:43.942432Z","end":"2026-02-25T10:04:44.202916Z","steps":["trace[1833874944] 'process raft request' (duration: 227.299018ms)","trace[1833874944] 'compare' (duration: 32.88011ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:44.203067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.891606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:44.203135Z","caller":"traceutil/trace.go:171","msg":"trace[735255733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"169.992748ms","start":"2026-02-25T10:04:44.033125Z","end":"2026-02-25T10:04:44.203117Z","steps":["trace[735255733] 'agreement among raft nodes before linearized reading' (duration: 169.890226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:44.203315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.849777ms","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-25T10:04:44.203414Z","caller":"traceutil/trace.go:171","msg":"trace[1694222176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"102.991629ms","start":"2026-02-25T10:04:44.100396Z","end":"2026-02-25T10:04:44.203388Z","steps":["trace[1694222176] 'agreement among raft nodes before linearized reading' (duration: 102.673732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:44.203489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.644287ms","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-25T10:04:44.203539Z","caller":"traceutil/trace.go:171","msg":"trace[85100473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"100.706228ms","start":"2026-02-25T10:04:44.102817Z","end":"2026-02-25T10:04:44.203523Z","steps":["trace[85100473] 'agreement among raft nodes before linearized reading' (duration: 100.505804ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:44.357198Z","caller":"traceutil/trace.go:171","msg":"trace[1770657788] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"148.32834ms","start":"2026-02-25T10:04:44.208831Z","end":"2026-02-25T10:04:44.357159Z","steps":["trace[1770657788] 'process raft request' (duration: 123.195324ms)","trace[1770657788] 'compare' (duration: 24.92528ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:04:44.357477Z","caller":"traceutil/trace.go:171","msg":"trace[1109011442] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"148.498224ms","start":"2026-02-25T10:04:44.208959Z","end":"2026-02-25T10:04:44.357457Z","steps":["trace[1109011442] 'process raft request' (duration: 148.438192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:58.772778Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731959215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:59.273369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731959215,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:04:59.286853Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.218569449s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:04:59.287373Z","caller":"traceutil/trace.go:171","msg":"trace[90873109] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"1.219171462s","start":"2026-02-25T10:04:58.068179Z","end":"2026-02-25T10:04:59.287351Z","steps":["trace[90873109] 'process raft request' (duration: 1.219041769s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:59.287496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:58.068157Z","time spent":"1.219268205s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:00.242761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.078571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:00.243008Z","caller":"traceutil/trace.go:171","msg":"trace[1097737532] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"1.959706617s","start":"2026-02-25T10:04:58.283265Z","end":"2026-02-25T10:05:00.242972Z","steps":["trace[1097737532] 'process raft request' (duration: 1.685335339s)","trace[1097737532] 'compare' (duration: 273.746184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:00.243185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:58.283246Z","time spent":"1.95985176s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:01.026308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.37619ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T10:05:01.026427Z","caller":"traceutil/trace.go:171","msg":"trace[2043562256] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:934; }","duration":"2.754509774s","start":"2026-02-25T10:04:58.271898Z","end":"2026-02-25T10:05:01.026408Z","steps":["trace[2043562256] 'read index received' (duration: 1.015304512s)","trace[2043562256] 'applied index is now lower than readState.Index' (duration: 1.739203762s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:01.026751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.421860053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T10:05:01.026741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.594192024s","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-25T10:05:01.026764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672139052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T10:05:01.026839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995079204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:01.026836Z","caller":"traceutil/trace.go:171","msg":"trace[610788735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"2.594328047s","start":"2026-02-25T10:04:58.432488Z","end":"2026-02-25T10:05:01.026816Z","steps":["trace[610788735] 'agreement among raft nodes before linearized reading' (duration: 2.594087722s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:01.026865Z","caller":"traceutil/trace.go:171","msg":"trace[639690038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"1.995131815s","start":"2026-02-25T10:04:59.031725Z","end":"2026-02-25T10:05:01.026857Z","steps":["trace[639690038] 'agreement among raft nodes before linearized reading' (duration: 1.995096094s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:01.026856Z","caller":"traceutil/trace.go:171","msg":"trace[1748471627] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:898; }","duration":"1.672280366s","start":"2026-02-25T10:04:59.35455Z","end":"2026-02-25T10:05:01.026831Z","steps":["trace[1748471627] 'agreement among raft nodes before linearized reading' (duration: 1.672111222s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:01.026879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:58.432469Z","time spent":"2.59440136s","remote":"127.0.0.1:46572","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-25T10:05:01.026897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:59.031707Z","time spent":"1.995183266s","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:01.026921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:59.354527Z","time spent":"1.672377847s","remote":"127.0.0.1:46586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:05:01.027077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.699189ms","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-25T10:05:01.027106Z","caller":"traceutil/trace.go:171","msg":"trace[1978294800] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:898; }","duration":"299.73395ms","start":"2026-02-25T10:05:00.727364Z","end":"2026-02-25T10:05:01.027098Z","steps":["trace[1978294800] 'agreement among raft nodes before linearized reading' (duration: 299.668188ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:01.026801Z","caller":"traceutil/trace.go:171","msg":"trace[2056652557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"2.421916585s","start":"2026-02-25T10:04:58.604869Z","end":"2026-02-25T10:05:01.026786Z","steps":["trace[2056652557] 'agreement among raft nodes before linearized reading' (duration: 2.421799232s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:01.027137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.387631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:05:01.027163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:58.604817Z","time spent":"2.422334464s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:05:01.027176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.559837832s","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-25T10:05:01.02721Z","caller":"traceutil/trace.go:171","msg":"trace[66573623] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:898; }","duration":"263.513144ms","start":"2026-02-25T10:05:00.763679Z","end":"2026-02-25T10:05:01.027193Z","steps":["trace[66573623] 'agreement among raft nodes before linearized reading' (duration: 263.36303ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:01.027258Z","caller":"traceutil/trace.go:171","msg":"trace[399545102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"2.559958724s","start":"2026-02-25T10:04:58.467277Z","end":"2026-02-25T10:05:01.027235Z","steps":["trace[399545102] 'agreement among raft nodes before linearized reading' (duration: 2.559723118s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:01.027307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:58.467256Z","time spent":"2.560041096s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:01.027328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.755434164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-25T10:05:01.027486Z","caller":"traceutil/trace.go:171","msg":"trace[1183789620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"2.755623919s","start":"2026-02-25T10:04:58.271847Z","end":"2026-02-25T10:05:01.027471Z","steps":["trace[1183789620] 'agreement among raft nodes before linearized reading' (duration: 2.754690318s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:01.027705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:58.271827Z","time spent":"2.755866454s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:05:01.16765Z","caller":"traceutil/trace.go:171","msg":"trace[2122176831] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"135.292576ms","start":"2026-02-25T10:05:01.032335Z","end":"2026-02-25T10:05:01.167627Z","steps":["trace[2122176831] 'process raft request' (duration: 135.131792ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:01.45003Z","caller":"traceutil/trace.go:171","msg":"trace[1150678113] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:937; }","duration":"413.394808ms","start":"2026-02-25T10:05:01.036606Z","end":"2026-02-25T10:05:01.450001Z","steps":["trace[1150678113] 'read index received' (duration: 130.898027ms)","trace[1150678113] 'applied index is now lower than readState.Index' (duration: 282.495271ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:01.450071Z","caller":"traceutil/trace.go:171","msg":"trace[1528068345] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"417.04508ms","start":"2026-02-25T10:05:01.033005Z","end":"2026-02-25T10:05:01.45005Z","steps":["trace[1528068345] 'process raft request' (duration: 416.920557ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:01.450151Z","caller":"traceutil/trace.go:171","msg":"trace[1271854980] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"417.302067ms","start":"2026-02-25T10:05:01.032816Z","end":"2026-02-25T10:05:01.450118Z","steps":["trace[1271854980] 'process raft request' (duration: 416.955319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:01.450531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:01.032982Z","time spent":"417.439829ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:01.45013Z","caller":"traceutil/trace.go:171","msg":"trace[599417574] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"416.307014ms","start":"2026-02-25T10:05:01.033812Z","end":"2026-02-25T10:05:01.450119Z","steps":["trace[599417574] 'process raft request' (duration: 416.14946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:01.450666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:01.032799Z","time spent":"417.737196ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:01.450673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:01.033791Z","time spent":"416.843305ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:01.45021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.573372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:01.450854Z","caller":"traceutil/trace.go:171","msg":"trace[1085666410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:902; }","duration":"414.256127ms","start":"2026-02-25T10:05:01.036585Z","end":"2026-02-25T10:05:01.450841Z","steps":["trace[1085666410] 'agreement among raft nodes before linearized reading' (duration: 413.560131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:01.450895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:01.03657Z","time spent":"414.314878ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:01.451318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.556073ms","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-25T10:05:01.451412Z","caller":"traceutil/trace.go:171","msg":"trace[1500926370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"159.692626ms","start":"2026-02-25T10:05:01.291703Z","end":"2026-02-25T10:05:01.451396Z","steps":["trace[1500926370] 'agreement among raft nodes before linearized reading' (duration: 159.500042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.021039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731959316,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:05:14.031908Z","caller":"traceutil/trace.go:171","msg":"trace[1264300711] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"511.538028ms","start":"2026-02-25T10:05:13.520351Z","end":"2026-02-25T10:05:14.031889Z","steps":["trace[1264300711] 'read index received' (duration: 511.416965ms)","trace[1264300711] 'applied index is now lower than readState.Index' (duration: 120.323µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:14.032101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.734904ms","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-25T10:05:14.032129Z","caller":"traceutil/trace.go:171","msg":"trace[238532005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"511.828855ms","start":"2026-02-25T10:05:13.520293Z","end":"2026-02-25T10:05:14.032122Z","steps":["trace[238532005] 'agreement among raft nodes before linearized reading' (duration: 511.707822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.032168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:13.520262Z","time spent":"511.898537ms","remote":"127.0.0.1:46572","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":"info","ts":"2026-02-25T10:05:14.032172Z","caller":"traceutil/trace.go:171","msg":"trace[948607715] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"528.173304ms","start":"2026-02-25T10:05:13.50394Z","end":"2026-02-25T10:05:14.032113Z","steps":["trace[948607715] 'process raft request' (duration: 527.848606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.032316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.976288ms","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-25T10:05:14.032342Z","caller":"traceutil/trace.go:171","msg":"trace[1775339463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"500.03592ms","start":"2026-02-25T10:05:13.532295Z","end":"2026-02-25T10:05:14.032331Z","steps":["trace[1775339463] 'agreement among raft nodes before linearized reading' (duration: 499.994519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.032369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:13.532274Z","time spent":"500.090021ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:05:14.032263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.089547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T10:05:14.032472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:13.503909Z","time spent":"528.442939ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:14.032474Z","caller":"traceutil/trace.go:171","msg":"trace[103729406] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"502.347632ms","start":"2026-02-25T10:05:13.530101Z","end":"2026-02-25T10:05:14.032449Z","steps":["trace[103729406] 'agreement among raft nodes before linearized reading' (duration: 502.075366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:14.032612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:13.530076Z","time spent":"502.523907ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T10:05:20.518068Z","caller":"traceutil/trace.go:171","msg":"trace[1138903663] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"457.950743ms","start":"2026-02-25T10:05:20.060094Z","end":"2026-02-25T10:05:20.518045Z","steps":["trace[1138903663] 'read index received' (duration: 457.722788ms)","trace[1138903663] 'applied index is now lower than readState.Index' (duration: 226.925µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:20.518201Z","caller":"traceutil/trace.go:171","msg":"trace[196976370] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"459.782634ms","start":"2026-02-25T10:05:20.058385Z","end":"2026-02-25T10:05:20.518167Z","steps":["trace[196976370] 'process raft request' (duration: 459.508698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:20.518346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.222009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-25T10:05:20.518371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:20.058368Z","time spent":"459.913446ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:20.518418Z","caller":"traceutil/trace.go:171","msg":"trace[1435789307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:977; }","duration":"458.325231ms","start":"2026-02-25T10:05:20.060075Z","end":"2026-02-25T10:05:20.5184Z","steps":["trace[1435789307] 'agreement among raft nodes before linearized reading' (duration: 458.094096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:20.518498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:20.060069Z","time spent":"458.417563ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:20.518574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.520542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T10:05:20.518607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.2059ms","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-25T10:05:20.518671Z","caller":"traceutil/trace.go:171","msg":"trace[1190474070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"451.320983ms","start":"2026-02-25T10:05:20.067338Z","end":"2026-02-25T10:05:20.518659Z","steps":["trace[1190474070] 'agreement among raft nodes before linearized reading' (duration: 451.18311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:20.518698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:20.067322Z","time spent":"451.371204ms","remote":"127.0.0.1:46572","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-25T10:05:20.518642Z","caller":"traceutil/trace.go:171","msg":"trace[916510351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:977; }","duration":"453.619695ms","start":"2026-02-25T10:05:20.065005Z","end":"2026-02-25T10:05:20.518625Z","steps":["trace[916510351] 'agreement among raft nodes before linearized reading' (duration: 453.292468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:20.518792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:20.064976Z","time spent":"453.808359ms","remote":"127.0.0.1:46572","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-25T10:05:21.533406Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731959404,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:21.743777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.432712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:21.744647Z","caller":"traceutil/trace.go:171","msg":"trace[648017337] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1020; }","duration":"712.29723ms","start":"2026-02-25T10:05:21.03233Z","end":"2026-02-25T10:05:21.744627Z","steps":["trace[648017337] 'read index received' (duration: 693.652999ms)","trace[648017337] 'applied index is now lower than readState.Index' (duration: 18.643171ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:21.744637Z","caller":"traceutil/trace.go:171","msg":"trace[600354851] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.221855353s","start":"2026-02-25T10:05:20.522717Z","end":"2026-02-25T10:05:21.744573Z","steps":["trace[600354851] 'process raft request' (duration: 468.545621ms)","trace[600354851] 'compare' (duration: 752.279519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:21.744918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.574185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:21.745007Z","caller":"traceutil/trace.go:171","msg":"trace[2046209400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"712.689158ms","start":"2026-02-25T10:05:21.032275Z","end":"2026-02-25T10:05:21.744965Z","steps":["trace[2046209400] 'agreement among raft nodes before linearized reading' (duration: 712.576046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:21.745017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:20.522695Z","time spent":"1.222261863s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:21.745085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:21.032253Z","time spent":"712.795221ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:05:21.744749Z","caller":"traceutil/trace.go:171","msg":"trace[660332509] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.220536513s","start":"2026-02-25T10:05:20.524202Z","end":"2026-02-25T10:05:21.744738Z","steps":["trace[660332509] 'process raft request' (duration: 1.220332659s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:21.745442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:20.524188Z","time spent":"1.221208219s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:21.744784Z","caller":"traceutil/trace.go:171","msg":"trace[1869039730] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.210538728s","start":"2026-02-25T10:05:20.534231Z","end":"2026-02-25T10:05:21.74477Z","steps":["trace[1869039730] 'process raft request' (duration: 1.210336843s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:21.745676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:20.534215Z","time spent":"1.211415168s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:21.744784Z","caller":"traceutil/trace.go:171","msg":"trace[1873584221] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.221321361s","start":"2026-02-25T10:05:20.523455Z","end":"2026-02-25T10:05:21.744777Z","steps":["trace[1873584221] 'process raft request' (duration: 1.221009264s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:21.745922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:20.523439Z","time spent":"1.222403915s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:21.747187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.977546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T10:05:21.747298Z","caller":"traceutil/trace.go:171","msg":"trace[1120600354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"495.123949ms","start":"2026-02-25T10:05:21.252156Z","end":"2026-02-25T10:05:21.74728Z","steps":["trace[1120600354] 'agreement among raft nodes before linearized reading' (duration: 493.322249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:21.747616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:21.252138Z","time spent":"495.461287ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:23.219852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.482052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:23.219981Z","caller":"traceutil/trace.go:171","msg":"trace[369733418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"187.658395ms","start":"2026-02-25T10:05:23.032296Z","end":"2026-02-25T10:05:23.219955Z","steps":["trace[369733418] 'range keys from in-memory index tree' (duration: 187.39943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:23.220002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.553168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-25T10:05:23.220036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.411014ms","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-25T10:05:23.220063Z","caller":"traceutil/trace.go:171","msg":"trace[256232518] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:985; }","duration":"296.66568ms","start":"2026-02-25T10:05:22.92338Z","end":"2026-02-25T10:05:23.220046Z","steps":["trace[256232518] 'range keys from in-memory index tree' (duration: 296.391384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:23.220052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.04455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:23.220102Z","caller":"traceutil/trace.go:171","msg":"trace[20470015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:985; }","duration":"697.513826ms","start":"2026-02-25T10:05:22.522571Z","end":"2026-02-25T10:05:23.220085Z","steps":["trace[20470015] 'range keys from in-memory index tree' (duration: 697.252911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:23.220153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:22.522555Z","time spent":"697.589188ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T10:05:23.220145Z","caller":"traceutil/trace.go:171","msg":"trace[960753945] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:985; }","duration":"687.174163ms","start":"2026-02-25T10:05:22.532945Z","end":"2026-02-25T10:05:23.220119Z","steps":["trace[960753945] 'range keys from in-memory index tree' (duration: 686.963779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:23.220232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:22.53289Z","time spent":"687.330496ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T10:05:23.788731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731959430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:24.289137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731959430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:24.789457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810592731959430,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:05:24.920899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.331993ms","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-25T10:05:24.920987Z","caller":"traceutil/trace.go:171","msg":"trace[180482557] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:989; }","duration":"574.433686ms","start":"2026-02-25T10:05:24.34654Z","end":"2026-02-25T10:05:24.920973Z","steps":["trace[180482557] 'range keys from in-memory index tree' (duration: 574.320533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.946419Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.660784577s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:05:24.94731Z","caller":"traceutil/trace.go:171","msg":"trace[2082980066] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.686096557s","start":"2026-02-25T10:05:23.261196Z","end":"2026-02-25T10:05:24.947292Z","steps":["trace[2082980066] 'process raft request' (duration: 1.686056616s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:24.947378Z","caller":"traceutil/trace.go:171","msg":"trace[1875724789] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.687165031s","start":"2026-02-25T10:05:23.260193Z","end":"2026-02-25T10:05:24.947358Z","steps":["trace[1875724789] 'process raft request' (duration: 1.686942916s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:24.94742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.261183Z","time spent":"1.686183839s","remote":"127.0.0.1:46388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:24.947504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.260175Z","time spent":"1.687256042s","remote":"127.0.0.1:46388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:05:25.231473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.551949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:25.231561Z","caller":"traceutil/trace.go:171","msg":"trace[381633597] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1033; }","duration":"1.9438277s","start":"2026-02-25T10:05:23.287721Z","end":"2026-02-25T10:05:25.231548Z","steps":["trace[381633597] 'read index received' (duration: 1.659423936s)","trace[381633597] 'applied index is now lower than readState.Index' (duration: 284.402724ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:25.231571Z","caller":"traceutil/trace.go:171","msg":"trace[1477149205] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.941364815s","start":"2026-02-25T10:05:23.290188Z","end":"2026-02-25T10:05:25.231553Z","steps":["trace[1477149205] 'process raft request' (duration: 1.790664302s)","trace[1477149205] 'compare' (duration: 150.384016ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:25.231669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.290163Z","time spent":"1.941456417s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:25.231671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943944172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-25T10:05:25.231707Z","caller":"traceutil/trace.go:171","msg":"trace[79539669] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:992; }","duration":"1.944006863s","start":"2026-02-25T10:05:23.28769Z","end":"2026-02-25T10:05:25.231697Z","steps":["trace[79539669] 'agreement among raft nodes before linearized reading' (duration: 1.943922831s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:25.2317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54058469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T10:05:25.231738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.287619Z","time spent":"1.944111057s","remote":"127.0.0.1:46472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-25T10:05:25.231755Z","caller":"traceutil/trace.go:171","msg":"trace[176204141] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:992; }","duration":"1.540686483s","start":"2026-02-25T10:05:23.691055Z","end":"2026-02-25T10:05:25.231742Z","steps":["trace[176204141] 'agreement among raft nodes before linearized reading' (duration: 1.540588841s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:25.231785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.691037Z","time spent":"1.540740394s","remote":"127.0.0.1:42646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:05:25.231833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459925614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-25T10:05:25.231863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482025973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-25T10:05:25.231879Z","caller":"traceutil/trace.go:171","msg":"trace[1324158596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"1.482080024s","start":"2026-02-25T10:05:23.749794Z","end":"2026-02-25T10:05:25.231874Z","steps":["trace[1324158596] 'agreement among raft nodes before linearized reading' (duration: 1.482031042s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:25.23188Z","caller":"traceutil/trace.go:171","msg":"trace[518894469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"1.459974466s","start":"2026-02-25T10:05:23.771893Z","end":"2026-02-25T10:05:25.231867Z","steps":["trace[518894469] 'agreement among raft nodes before linearized reading' (duration: 1.459801631s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:25.231896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.749772Z","time spent":"1.482120845s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:25.231911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.771831Z","time spent":"1.460072689s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:05:25.231906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.845166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:05:25.231975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48196249s","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-25T10:05:25.231964Z","caller":"traceutil/trace.go:171","msg":"trace[1021980126] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:992; }","duration":"185.925068ms","start":"2026-02-25T10:05:25.046023Z","end":"2026-02-25T10:05:25.231948Z","steps":["trace[1021980126] 'agreement among raft nodes before linearized reading' (duration: 185.697692ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:25.231988Z","caller":"traceutil/trace.go:171","msg":"trace[1319751115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"1.481995941s","start":"2026-02-25T10:05:23.749988Z","end":"2026-02-25T10:05:25.231984Z","steps":["trace[1319751115] 'agreement among raft nodes before linearized reading' (duration: 1.481958831s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:25.232021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.749978Z","time spent":"1.482040122s","remote":"127.0.0.1:46572","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-25T10:05:25.232107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200272018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:25.232121Z","caller":"traceutil/trace.go:171","msg":"trace[139287748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"1.20035522s","start":"2026-02-25T10:05:24.031762Z","end":"2026-02-25T10:05:25.232117Z","steps":["trace[139287748] 'agreement among raft nodes before linearized reading' (duration: 1.20033342s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:25.232118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48235007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T10:05:25.232132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:24.031742Z","time spent":"1.200387721s","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:05:25.232146Z","caller":"traceutil/trace.go:171","msg":"trace[1100458001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"1.482378111s","start":"2026-02-25T10:05:23.74976Z","end":"2026-02-25T10:05:25.232138Z","steps":["trace[1100458001] 'agreement among raft nodes before linearized reading' (duration: 1.482232108s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:25.232165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:23.749708Z","time spent":"1.482452542s","remote":"127.0.0.1:46572","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-25T10:05:25.625932Z","caller":"traceutil/trace.go:171","msg":"trace[1695798473] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1037; }","duration":"310.808979ms","start":"2026-02-25T10:05:25.315094Z","end":"2026-02-25T10:05:25.625903Z","steps":["trace[1695798473] 'read index received' (duration: 310.639055ms)","trace[1695798473] 'applied index is now lower than readState.Index' (duration: 168.674µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:25.625954Z","caller":"traceutil/trace.go:171","msg":"trace[453308774] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"389.568982ms","start":"2026-02-25T10:05:25.23636Z","end":"2026-02-25T10:05:25.625929Z","steps":["trace[453308774] 'process raft request' (duration: 389.44719ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:25.626013Z","caller":"traceutil/trace.go:171","msg":"trace[1762684704] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"390.211886ms","start":"2026-02-25T10:05:25.235793Z","end":"2026-02-25T10:05:25.626005Z","steps":["trace[1762684704] 'process raft request' (duration: 389.872448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:25.626117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:25.236354Z","time spent":"389.672214ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:25.626273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.59778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:05:25.626292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.555794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T10:05:25.626315Z","caller":"traceutil/trace.go:171","msg":"trace[1422133954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:996; }","duration":"366.578655ms","start":"2026-02-25T10:05:25.259731Z","end":"2026-02-25T10:05:25.62631Z","steps":["trace[1422133954] 'agreement among raft nodes before linearized reading' (duration: 366.491643ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:25.626312Z","caller":"traceutil/trace.go:171","msg":"trace[936022814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"384.647462ms","start":"2026-02-25T10:05:25.241654Z","end":"2026-02-25T10:05:25.626302Z","steps":["trace[936022814] 'agreement among raft nodes before linearized reading' (duration: 384.58915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:25.62633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:25.259697Z","time spent":"366.629696ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:05:25.626344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:25.241649Z","time spent":"384.687752ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:25.626131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:25.235787Z","time spent":"390.259397ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:25.626527Z","caller":"traceutil/trace.go:171","msg":"trace[1690152959] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"389.474089ms","start":"2026-02-25T10:05:25.237047Z","end":"2026-02-25T10:05:25.626521Z","steps":["trace[1690152959] 'process raft request' (duration: 388.808215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:25.626559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:25.237041Z","time spent":"389.498541ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:26.622951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.21663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:26.623398Z","caller":"traceutil/trace.go:171","msg":"trace[10514258] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"994.139075ms","start":"2026-02-25T10:05:25.629235Z","end":"2026-02-25T10:05:26.623375Z","steps":["trace[10514258] 'process raft request' (duration: 993.827328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:26.623479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:25.629211Z","time spent":"994.226118ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:05:26.623393Z","caller":"traceutil/trace.go:171","msg":"trace[1259110167] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.289243s","start":"2026-02-25T10:05:25.334122Z","end":"2026-02-25T10:05:26.623365Z","steps":["trace[1259110167] 'process raft request' (duration: 396.530349ms)","trace[1259110167] 'compare' (duration: 891.909483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:26.623703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:25.334104Z","time spent":"1.289566918s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:27.353182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.703216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:27.353278Z","caller":"traceutil/trace.go:171","msg":"trace[1325639545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"322.859269ms","start":"2026-02-25T10:05:27.030394Z","end":"2026-02-25T10:05:27.353254Z","steps":["trace[1325639545] 'range keys from in-memory index tree' (duration: 322.593243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:27.353322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:27.030373Z","time spent":"322.939322ms","remote":"127.0.0.1:46290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:05:27.353404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.962725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:27.353612Z","caller":"traceutil/trace.go:171","msg":"trace[510428797] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:998; }","duration":"149.65644ms","start":"2026-02-25T10:05:27.203935Z","end":"2026-02-25T10:05:27.353591Z","steps":["trace[510428797] 'count revisions from in-memory index tree' (duration: 148.864372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:27.850007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.86297ms","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-25T10:05:27.850094Z","caller":"traceutil/trace.go:171","msg":"trace[1108343208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"219.994203ms","start":"2026-02-25T10:05:27.630081Z","end":"2026-02-25T10:05:27.850075Z","steps":["trace[1108343208] 'range keys from in-memory index tree' (duration: 219.676826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:27.850267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.137516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T10:05:27.850283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.40298ms","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-25T10:05:27.850338Z","caller":"traceutil/trace.go:171","msg":"trace[941736663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1000; }","duration":"219.493272ms","start":"2026-02-25T10:05:27.630831Z","end":"2026-02-25T10:05:27.850324Z","steps":["trace[941736663] 'range keys from in-memory index tree' (duration: 219.250337ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:27.850332Z","caller":"traceutil/trace.go:171","msg":"trace[731966396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"220.236759ms","start":"2026-02-25T10:05:27.630081Z","end":"2026-02-25T10:05:27.850318Z","steps":["trace[731966396] 'range keys from in-memory index tree' (duration: 219.979343ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:28.011633Z","caller":"traceutil/trace.go:171","msg":"trace[1201222992] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"156.76165ms","start":"2026-02-25T10:05:27.854843Z","end":"2026-02-25T10:05:28.011605Z","steps":["trace[1201222992] 'process raft request' (duration: 99.029799ms)","trace[1201222992] 'compare' (duration: 57.506146ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:28.01197Z","caller":"traceutil/trace.go:171","msg":"trace[1873182464] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"155.4175ms","start":"2026-02-25T10:05:27.856487Z","end":"2026-02-25T10:05:28.011905Z","steps":["trace[1873182464] 'process raft request' (duration: 155.077313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:29.95322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.153133ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T10:05:29.95334Z","caller":"traceutil/trace.go:171","msg":"trace[1961249851] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"1.325767182s","start":"2026-02-25T10:05:28.627543Z","end":"2026-02-25T10:05:29.95331Z","steps":["trace[1961249851] 'read index received' (duration: 348.482986ms)","trace[1961249851] 'applied index is now lower than readState.Index' (duration: 977.282696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:29.953731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326170331s","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-25T10:05:29.95395Z","caller":"traceutil/trace.go:171","msg":"trace[1994119791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"1.326434567s","start":"2026-02-25T10:05:28.627501Z","end":"2026-02-25T10:05:29.953936Z","steps":["trace[1994119791] 'agreement among raft nodes before linearized reading' (duration: 1.32608679s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:29.954032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:28.627479Z","time spent":"1.326529419s","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:05:29.95424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.739281ms","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-25T10:05:29.954312Z","caller":"traceutil/trace.go:171","msg":"trace[1674181640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"570.859794ms","start":"2026-02-25T10:05:29.383433Z","end":"2026-02-25T10:05:29.954293Z","steps":["trace[1674181640] 'agreement among raft nodes before linearized reading' (duration: 570.733381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:29.954351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:29.383405Z","time spent":"570.937696ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T10:05:32.509064Z","caller":"traceutil/trace.go:171","msg":"trace[583192062] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"103.447359ms","start":"2026-02-25T10:05:32.405593Z","end":"2026-02-25T10:05:32.50904Z","steps":["trace[583192062] 'process raft request' (duration: 103.276845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:33.032115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.136462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T10:05:33.032205Z","caller":"traceutil/trace.go:171","msg":"trace[1370786017] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:1017; }","duration":"107.270095ms","start":"2026-02-25T10:05:32.924914Z","end":"2026-02-25T10:05:33.032184Z","steps":["trace[1370786017] 'range keys from in-memory index tree' (duration: 106.919957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:33.032756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.032885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:33.032827Z","caller":"traceutil/trace.go:171","msg":"trace[841892885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"252.158338ms","start":"2026-02-25T10:05:32.780652Z","end":"2026-02-25T10:05:33.032811Z","steps":["trace[841892885] 'range keys from in-memory index tree' (duration: 251.870172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:35.874233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.141454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:05:35.874404Z","caller":"traceutil/trace.go:171","msg":"trace[1992459930] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"428.341223ms","start":"2026-02-25T10:05:35.446048Z","end":"2026-02-25T10:05:35.87439Z","steps":["trace[1992459930] 'process raft request' (duration: 100.956323ms)","trace[1992459930] 'compare' (duration: 326.015948ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:35.874527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:35.446027Z","time spent":"428.405984ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:05:35.874346Z","caller":"traceutil/trace.go:171","msg":"trace[31065477] linearizableReadLoop","detail":"{readStateIndex:1082; appliedIndex:1081; }","duration":"227.288897ms","start":"2026-02-25T10:05:35.647043Z","end":"2026-02-25T10:05:35.874332Z","steps":["trace[31065477] 'read index received' (duration: 68.061µs)","trace[31065477] 'applied index is now lower than readState.Index' (duration: 227.219516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:05:35.874736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.673236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:05:35.874989Z","caller":"traceutil/trace.go:171","msg":"trace[1394998500] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1036; }","duration":"227.976463ms","start":"2026-02-25T10:05:35.646963Z","end":"2026-02-25T10:05:35.874939Z","steps":["trace[1394998500] 'agreement among raft nodes before linearized reading' (duration: 227.534223ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:43.466661Z","caller":"traceutil/trace.go:171","msg":"trace[307612099] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"118.074427ms","start":"2026-02-25T10:05:43.348561Z","end":"2026-02-25T10:05:43.466636Z","steps":["trace[307612099] 'process raft request' (duration: 117.862293ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:05:44.115476Z","caller":"traceutil/trace.go:171","msg":"trace[1092500041] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"100.191075ms","start":"2026-02-25T10:05:44.015261Z","end":"2026-02-25T10:05:44.115452Z","steps":["trace[1092500041] 'process raft request' (duration: 100.049092ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:08.721747Z","caller":"traceutil/trace.go:171","msg":"trace[815143014] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"116.956813ms","start":"2026-02-25T10:06:08.604769Z","end":"2026-02-25T10:06:08.721726Z","steps":["trace[815143014] 'process raft request' (duration: 85.398703ms)","trace[815143014] 'compare' (duration: 31.394157ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:08.722192Z","caller":"traceutil/trace.go:171","msg":"trace[1566027121] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"117.160598ms","start":"2026-02-25T10:06:08.605006Z","end":"2026-02-25T10:06:08.722166Z","steps":["trace[1566027121] 'process raft request' (duration: 116.688338ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:08.722393Z","caller":"traceutil/trace.go:171","msg":"trace[1048002313] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"117.276581ms","start":"2026-02-25T10:06:08.605095Z","end":"2026-02-25T10:06:08.722371Z","steps":["trace[1048002313] 'process raft request' (duration: 116.969444ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:08.722492Z","caller":"traceutil/trace.go:171","msg":"trace[332517656] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"117.362542ms","start":"2026-02-25T10:06:08.605118Z","end":"2026-02-25T10:06:08.722481Z","steps":["trace[332517656] 'process raft request' (duration: 117.186168ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:10.67184Z","caller":"traceutil/trace.go:171","msg":"trace[955356923] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"248.840002ms","start":"2026-02-25T10:06:10.42297Z","end":"2026-02-25T10:06:10.67181Z","steps":["trace[955356923] 'process raft request' (duration: 190.146351ms)","trace[955356923] 'compare' (duration: 58.426515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:13.072347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.025483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:06:13.072473Z","caller":"traceutil/trace.go:171","msg":"trace[1905456711] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"320.356422ms","start":"2026-02-25T10:06:12.752077Z","end":"2026-02-25T10:06:13.072434Z","steps":["trace[1905456711] 'read index received' (duration: 104.113144ms)","trace[1905456711] 'applied index is now lower than readState.Index' (duration: 216.242408ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:13.072539Z","caller":"traceutil/trace.go:171","msg":"trace[882628590] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"369.802585ms","start":"2026-02-25T10:06:12.702728Z","end":"2026-02-25T10:06:13.07253Z","steps":["trace[882628590] 'process raft request' (duration: 153.514666ms)","trace[882628590] 'compare' (duration: 215.831999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:06:13.072578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:12.702702Z","time spent":"369.854256ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:13.072729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.637968ms","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-25T10:06:13.072803Z","caller":"traceutil/trace.go:171","msg":"trace[76775915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1198; }","duration":"320.757031ms","start":"2026-02-25T10:06:12.752026Z","end":"2026-02-25T10:06:13.072783Z","steps":["trace[76775915] 'agreement among raft nodes before linearized reading' (duration: 320.567676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:13.072801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.664947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T10:06:13.072843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:12.752006Z","time spent":"320.828212ms","remote":"127.0.0.1:46572","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-25T10:06:13.072858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.859041ms","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-25T10:06:13.072872Z","caller":"traceutil/trace.go:171","msg":"trace[1235158902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"315.81769ms","start":"2026-02-25T10:06:12.757034Z","end":"2026-02-25T10:06:13.072852Z","steps":["trace[1235158902] 'agreement among raft nodes before linearized reading' (duration: 315.662547ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:13.072889Z","caller":"traceutil/trace.go:171","msg":"trace[1802325018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"315.932073ms","start":"2026-02-25T10:06:12.756944Z","end":"2026-02-25T10:06:13.072876Z","steps":["trace[1802325018] 'agreement among raft nodes before linearized reading' (duration: 315.863591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:13.072911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:12.756934Z","time spent":"315.972303ms","remote":"127.0.0.1:46572","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-25T10:06:13.07291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:12.757015Z","time spent":"315.886591ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:06:13.073315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.487107ms","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-25T10:06:13.073352Z","caller":"traceutil/trace.go:171","msg":"trace[607204124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1198; }","duration":"317.575659ms","start":"2026-02-25T10:06:12.755764Z","end":"2026-02-25T10:06:13.07334Z","steps":["trace[607204124] 'agreement among raft nodes before linearized reading' (duration: 316.793022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:13.07338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:12.755744Z","time spent":"317.62912ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T10:06:49.518739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.754096ms","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-25T10:06:49.518848Z","caller":"traceutil/trace.go:171","msg":"trace[1358302990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"160.872388ms","start":"2026-02-25T10:06:49.357947Z","end":"2026-02-25T10:06:49.518819Z","steps":["trace[1358302990] 'range keys from in-memory index tree' (duration: 160.556151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.51884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.494828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T10:06:49.518953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.281115ms","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-25T10:06:49.519Z","caller":"traceutil/trace.go:171","msg":"trace[313656897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"147.711402ms","start":"2026-02-25T10:06:49.371261Z","end":"2026-02-25T10:06:49.518972Z","steps":["trace[313656897] 'range keys from in-memory index tree' (duration: 147.276042ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:06:49.519073Z","caller":"traceutil/trace.go:171","msg":"trace[989846014] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"152.53733ms","start":"2026-02-25T10:06:49.3665Z","end":"2026-02-25T10:06:49.519037Z","steps":["trace[989846014] 'range keys from in-memory index tree' (duration: 152.156613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.519763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.754752ms","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-25T10:06:49.520072Z","caller":"traceutil/trace.go:171","msg":"trace[944381102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1347; }","duration":"147.140391ms","start":"2026-02-25T10:06:49.372907Z","end":"2026-02-25T10:06:49.520047Z","steps":["trace[944381102] 'range keys from in-memory index tree' (duration: 145.596616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.848138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.957133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:06:49.848409Z","caller":"traceutil/trace.go:171","msg":"trace[1357313778] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"325.479601ms","start":"2026-02-25T10:06:49.522897Z","end":"2026-02-25T10:06:49.848376Z","steps":["trace[1357313778] 'process raft request' (duration: 122.131769ms)","trace[1357313778] 'compare' (duration: 202.702878ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:06:49.848441Z","caller":"traceutil/trace.go:171","msg":"trace[1802287647] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"324.746914ms","start":"2026-02-25T10:06:49.523678Z","end":"2026-02-25T10:06:49.848425Z","steps":["trace[1802287647] 'process raft request' (duration: 324.618731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.848532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:49.523668Z","time spent":"324.814136ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:06:49.848576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:49.522885Z","time spent":"325.590463ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:06:49.848954Z","caller":"traceutil/trace.go:171","msg":"trace[1914373285] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"323.654099ms","start":"2026-02-25T10:06:49.525273Z","end":"2026-02-25T10:06:49.848927Z","steps":["trace[1914373285] 'process raft request' (duration: 323.106977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:06:49.849157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:06:49.525244Z","time spent":"323.777022ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:09.604789Z","caller":"traceutil/trace.go:171","msg":"trace[639904857] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"160.798587ms","start":"2026-02-25T10:07:09.443957Z","end":"2026-02-25T10:07:09.604756Z","steps":["trace[639904857] 'process raft request' (duration: 160.491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:09.902707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.031219ms","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-25T10:07:09.902764Z","caller":"traceutil/trace.go:171","msg":"trace[658784489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1429; }","duration":"250.131322ms","start":"2026-02-25T10:07:09.652618Z","end":"2026-02-25T10:07:09.90275Z","steps":["trace[658784489] 'range keys from in-memory index tree' (duration: 249.879315ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:11.352329Z","caller":"traceutil/trace.go:171","msg":"trace[1627906083] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"148.925581ms","start":"2026-02-25T10:07:11.203379Z","end":"2026-02-25T10:07:11.352305Z","steps":["trace[1627906083] 'process raft request' (duration: 148.777968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.369002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.994529ms","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-25T10:07:12.369021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.858954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:12.369076Z","caller":"traceutil/trace.go:171","msg":"trace[1439738762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1438; }","duration":"329.102281ms","start":"2026-02-25T10:07:12.039956Z","end":"2026-02-25T10:07:12.369058Z","steps":["trace[1439738762] 'range keys from in-memory index tree' (duration: 328.837185ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:12.369107Z","caller":"traceutil/trace.go:171","msg":"trace[91365217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1438; }","duration":"339.986306ms","start":"2026-02-25T10:07:12.029097Z","end":"2026-02-25T10:07:12.369083Z","steps":["trace[91365217] 'range keys from in-memory index tree' (duration: 339.787782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.369117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.039944Z","time spent":"329.163862ms","remote":"127.0.0.1:46572","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-25T10:07:12.369133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.238311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T10:07:12.369106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.016781ms","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-25T10:07:12.369165Z","caller":"traceutil/trace.go:171","msg":"trace[391276248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1438; }","duration":"319.303392ms","start":"2026-02-25T10:07:12.049853Z","end":"2026-02-25T10:07:12.369157Z","steps":["trace[391276248] 'range keys from in-memory index tree' (duration: 319.111348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.369159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.029083Z","time spent":"340.064287ms","remote":"127.0.0.1:46300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:07:12.369186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.049836Z","time spent":"319.343733ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T10:07:12.369191Z","caller":"traceutil/trace.go:171","msg":"trace[285160424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1438; }","duration":"317.124213ms","start":"2026-02-25T10:07:12.052051Z","end":"2026-02-25T10:07:12.369176Z","steps":["trace[285160424] 'range keys from in-memory index tree' (duration: 316.899259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:12.369229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.052029Z","time spent":"317.193595ms","remote":"127.0.0.1:46572","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":"info","ts":"2026-02-25T10:07:12.905055Z","caller":"traceutil/trace.go:171","msg":"trace[2145088080] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"139.496288ms","start":"2026-02-25T10:07:12.765496Z","end":"2026-02-25T10:07:12.904992Z","steps":["trace[2145088080] 'process raft request' (duration: 139.260413ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:22.582207Z","caller":"traceutil/trace.go:171","msg":"trace[800492608] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1546; }","duration":"108.34998ms","start":"2026-02-25T10:07:22.473823Z","end":"2026-02-25T10:07:22.582173Z","steps":["trace[800492608] 'read index received' (duration: 108.171026ms)","trace[800492608] 'applied index is now lower than readState.Index' (duration: 176.974µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:22.582321Z","caller":"traceutil/trace.go:171","msg":"trace[1749894036] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"150.785642ms","start":"2026-02-25T10:07:22.431474Z","end":"2026-02-25T10:07:22.58226Z","steps":["trace[1749894036] 'process raft request' (duration: 150.460785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:22.582554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.708188ms","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-25T10:07:22.582648Z","caller":"traceutil/trace.go:171","msg":"trace[886394543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1480; }","duration":"108.890102ms","start":"2026-02-25T10:07:22.473734Z","end":"2026-02-25T10:07:22.582624Z","steps":["trace[886394543] 'agreement among raft nodes before linearized reading' (duration: 108.556915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:22.582706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.780562ms","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-25T10:07:22.582777Z","caller":"traceutil/trace.go:171","msg":"trace[623308187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1480; }","duration":"101.911695ms","start":"2026-02-25T10:07:22.480844Z","end":"2026-02-25T10:07:22.582756Z","steps":["trace[623308187] 'agreement among raft nodes before linearized reading' (duration: 101.66898ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:23.065161Z","caller":"traceutil/trace.go:171","msg":"trace[1999307475] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"102.187302ms","start":"2026-02-25T10:07:22.962949Z","end":"2026-02-25T10:07:23.065136Z","steps":["trace[1999307475] 'process raft request' (duration: 89.531139ms)","trace[1999307475] 'compare' (duration: 12.453589ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:08:36.252429Z","caller":"traceutil/trace.go:171","msg":"trace[255118230] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"165.12545ms","start":"2026-02-25T10:08:36.087283Z","end":"2026-02-25T10:08:36.252409Z","steps":["trace[255118230] 'process raft request' (duration: 165.010987ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:36.573703Z","caller":"traceutil/trace.go:171","msg":"trace[181436015] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1890; }","duration":"218.271691ms","start":"2026-02-25T10:08:36.355414Z","end":"2026-02-25T10:08:36.573686Z","steps":["trace[181436015] 'read index received' (duration: 218.127437ms)","trace[181436015] 'applied index is now lower than readState.Index' (duration: 143.794µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:08:36.573789Z","caller":"traceutil/trace.go:171","msg":"trace[217125965] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"372.892325ms","start":"2026-02-25T10:08:36.200854Z","end":"2026-02-25T10:08:36.573746Z","steps":["trace[217125965] 'process raft request' (duration: 372.743202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:36.573887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.462534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-25T10:08:36.573914Z","caller":"traceutil/trace.go:171","msg":"trace[656203879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1808; }","duration":"218.568268ms","start":"2026-02-25T10:08:36.355341Z","end":"2026-02-25T10:08:36.573909Z","steps":["trace[656203879] 'agreement among raft nodes before linearized reading' (duration: 218.455435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:36.573928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:36.20083Z","time spent":"373.024727ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:08:45.282292Z","caller":"traceutil/trace.go:171","msg":"trace[724456333] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"302.15723ms","start":"2026-02-25T10:08:44.98004Z","end":"2026-02-25T10:08:45.282197Z","steps":["trace[724456333] 'process raft request' (duration: 301.977876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:45.282635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:44.980012Z","time spent":"302.376095ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:08:45.298238Z","caller":"traceutil/trace.go:171","msg":"trace[187579317] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"317.944164ms","start":"2026-02-25T10:08:44.980248Z","end":"2026-02-25T10:08:45.298192Z","steps":["trace[187579317] 'process raft request' (duration: 317.587527ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:45.298128Z","caller":"traceutil/trace.go:171","msg":"trace[37361711] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1933; }","duration":"301.382013ms","start":"2026-02-25T10:08:44.99673Z","end":"2026-02-25T10:08:45.298112Z","steps":["trace[37361711] 'read index received' (duration: 285.303622ms)","trace[37361711] 'applied index is now lower than readState.Index' (duration: 16.077611ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:08:45.2985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.71273ms","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-25T10:08:45.298571Z","caller":"traceutil/trace.go:171","msg":"trace[555903398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1850; }","duration":"301.865843ms","start":"2026-02-25T10:08:44.996687Z","end":"2026-02-25T10:08:45.298553Z","steps":["trace[555903398] 'agreement among raft nodes before linearized reading' (duration: 301.594097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:45.298719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:44.996673Z","time spent":"301.989266ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:08:45.298909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:44.980233Z","time spent":"318.106628ms","remote":"127.0.0.1:46572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:08:47.482738Z","caller":"traceutil/trace.go:171","msg":"trace[340129800] linearizableReadLoop","detail":"{readStateIndex:1947; appliedIndex:1946; }","duration":"155.954144ms","start":"2026-02-25T10:08:47.326767Z","end":"2026-02-25T10:08:47.482721Z","steps":["trace[340129800] 'read index received' (duration: 155.77461ms)","trace[340129800] 'applied index is now lower than readState.Index' (duration: 178.964µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:08:47.482858Z","caller":"traceutil/trace.go:171","msg":"trace[1691938872] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"187.990462ms","start":"2026-02-25T10:08:47.29486Z","end":"2026-02-25T10:08:47.482851Z","steps":["trace[1691938872] 'process raft request' (duration: 187.700816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:47.483025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.244952ms","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-25T10:08:47.483052Z","caller":"traceutil/trace.go:171","msg":"trace[1674093106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1862; }","duration":"156.317923ms","start":"2026-02-25T10:08:47.326727Z","end":"2026-02-25T10:08:47.483045Z","steps":["trace[1674093106] 'agreement among raft nodes before linearized reading' (duration: 156.21698ms)"],"step_count":1}