{"level":"warn","ts":"2026-02-28T10:05:19.920801Z","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-28T10:05:19.920961Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-28T10:05:19.921077Z","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-28T10:05:19.921093Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-28T10:05:19.921115Z","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-28T10:05:19.921846Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-02-28T10:05:19.92193Z","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.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-28T10:05:19.926721Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.517481ms"} {"level":"info","ts":"2026-02-28T10:05:19.934612Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-28T10:05:19.934745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-28T10:05:19.934773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-28T10:05:19.934817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-28T10:05:19.93485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-28T10:05:19.935013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-28T10:05:19.940919Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-28T10:05:19.943339Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-28T10:05:19.944762Z","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-28T10:05:19.946572Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-28T10:05:19.946808Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-28T10:05:19.946859Z","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-28T10:05:19.947265Z","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-28T10:05:19.947286Z","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-28T10:05:19.948457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-28T10:05:19.951772Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-28T10:05:19.953164Z","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-28T10:05:19.953262Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-28T10:05:19.953301Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-28T10:05:19.953402Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-28T10:05:19.953435Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-28T10:05:20.23585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-28T10:05:20.235918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-28T10:05:20.235964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-28T10:05:20.235986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-28T10:05:20.235997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-28T10:05:20.236015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-28T10:05:20.236036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-28T10:05:20.236693Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T10:05:20.237475Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T10:05:20.237677Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T10:05:20.237468Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-02-28T10:05:20.237993Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-28T10:05:20.238011Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-28T10:05:20.238004Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T10:05:20.238106Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T10:05:20.238148Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T10:05:20.241135Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-28T10:05:20.241613Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-28T10:05:24.992153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.114568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T10:05:24.9923Z","caller":"traceutil/trace.go:171","msg":"trace[1400680612] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"917.197317ms","start":"2026-02-28T10:05:24.07508Z","end":"2026-02-28T10:05:24.992278Z","steps":["trace[1400680612] 'process raft request' (duration: 470.475144ms)","trace[1400680612] 'compare' (duration: 445.950655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:05:24.99237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:24.075067Z","time spent":"917.27638ms","remote":"127.0.0.1:47704","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-28T10:05:25.035748Z","caller":"traceutil/trace.go:171","msg":"trace[1438826290] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"959.673157ms","start":"2026-02-28T10:05:24.076045Z","end":"2026-02-28T10:05:25.035718Z","steps":["trace[1438826290] 'process raft request' (duration: 959.514132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:25.035872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:24.076031Z","time spent":"959.78621ms","remote":"127.0.0.1:47560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11440,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-28T10:05:25.035913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.339495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T10:05:25.035961Z","caller":"traceutil/trace.go:171","msg":"trace[1977754337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:249; }","duration":"837.423977ms","start":"2026-02-28T10:05:24.198522Z","end":"2026-02-28T10:05:25.035946Z","steps":["trace[1977754337] 'agreement among raft nodes before linearized reading' (duration: 837.294304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:25.035994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:24.198507Z","time spent":"837.479448ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-28T10:05:25.03575Z","caller":"traceutil/trace.go:171","msg":"trace[110408958] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:253; }","duration":"837.12302ms","start":"2026-02-28T10:05:24.198562Z","end":"2026-02-28T10:05:25.035685Z","steps":["trace[110408958] 'read index received' (duration: 347.006285ms)","trace[110408958] 'applied index is now lower than readState.Index' (duration: 490.115275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:05:25.81142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.235043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T10:05:25.81171Z","caller":"traceutil/trace.go:171","msg":"trace[582588015] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"770.088431ms","start":"2026-02-28T10:05:25.041581Z","end":"2026-02-28T10:05:25.811669Z","steps":["trace[582588015] 'process raft request' (duration: 635.499218ms)","trace[582588015] 'compare' (duration: 134.030868ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:05:25.811906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:25.041565Z","time spent":"770.234045ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":324,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T10:05:25.81213Z","caller":"traceutil/trace.go:171","msg":"trace[1077374902] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"770.402938ms","start":"2026-02-28T10:05:25.041709Z","end":"2026-02-28T10:05:25.812112Z","steps":["trace[1077374902] 'process raft request' (duration: 769.868425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:25.812196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:25.041702Z","time spent":"770.46504ms","remote":"127.0.0.1:47704","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-28T10:05:25.812272Z","caller":"traceutil/trace.go:171","msg":"trace[1527073458] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"770.385688ms","start":"2026-02-28T10:05:25.041847Z","end":"2026-02-28T10:05:25.812233Z","steps":["trace[1527073458] 'process raft request' (duration: 769.786524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:25.812744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:25.04182Z","time spent":"770.531441ms","remote":"127.0.0.1:47704","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-28T10:05:28.080882Z","caller":"traceutil/trace.go:171","msg":"trace[42678003] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:321; }","duration":"241.754992ms","start":"2026-02-28T10:05:27.839102Z","end":"2026-02-28T10:05:28.080857Z","steps":["trace[42678003] 'read index received' (duration: 234.286629ms)","trace[42678003] 'applied index is now lower than readState.Index' (duration: 7.467513ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T10:05:28.080968Z","caller":"traceutil/trace.go:171","msg":"trace[1323436641] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"248.817145ms","start":"2026-02-28T10:05:27.832127Z","end":"2026-02-28T10:05:28.080944Z","steps":["trace[1323436641] 'process raft request' (duration: 241.173937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:28.08103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.900095ms","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-28T10:05:28.081061Z","caller":"traceutil/trace.go:171","msg":"trace[1227040292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:313; }","duration":"241.972447ms","start":"2026-02-28T10:05:27.839078Z","end":"2026-02-28T10:05:28.081051Z","steps":["trace[1227040292] 'agreement among raft nodes before linearized reading' (duration: 241.888385ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:28.084279Z","caller":"traceutil/trace.go:171","msg":"trace[468153899] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"203.636189ms","start":"2026-02-28T10:05:27.880609Z","end":"2026-02-28T10:05:28.084245Z","steps":["trace[468153899] 'process raft request' (duration: 201.745843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:28.084388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.15244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-28T10:05:28.08447Z","caller":"traceutil/trace.go:171","msg":"trace[2127018758] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:313; }","duration":"244.258532ms","start":"2026-02-28T10:05:27.840176Z","end":"2026-02-28T10:05:28.084434Z","steps":["trace[2127018758] 'agreement among raft nodes before linearized reading' (duration: 242.236952ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:28.085088Z","caller":"traceutil/trace.go:171","msg":"trace[1161063934] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"204.256075ms","start":"2026-02-28T10:05:27.880811Z","end":"2026-02-28T10:05:28.085067Z","steps":["trace[1161063934] 'process raft request' (duration: 201.564619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:28.084931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.256325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-02-28T10:05:28.086304Z","caller":"traceutil/trace.go:171","msg":"trace[620770797] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:313; }","duration":"205.465614ms","start":"2026-02-28T10:05:27.880258Z","end":"2026-02-28T10:05:28.085724Z","steps":["trace[620770797] 'agreement among raft nodes before linearized reading' (duration: 204.0472ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:28.084227Z","caller":"traceutil/trace.go:171","msg":"trace[231817529] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"202.163683ms","start":"2026-02-28T10:05:27.880279Z","end":"2026-02-28T10:05:28.082442Z","steps":["trace[231817529] 'process raft request' (duration: 201.999789ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:50.100427Z","caller":"traceutil/trace.go:171","msg":"trace[1845458034] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"170.14882ms","start":"2026-02-28T10:05:49.930256Z","end":"2026-02-28T10:05:50.100405Z","steps":["trace[1845458034] 'process raft request' (duration: 169.978216ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:50.436475Z","caller":"traceutil/trace.go:171","msg":"trace[2051001103] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"248.290186ms","start":"2026-02-28T10:05:50.18816Z","end":"2026-02-28T10:05:50.43645Z","steps":["trace[2051001103] 'read index received' (duration: 248.151793ms)","trace[2051001103] 'applied index is now lower than readState.Index' (duration: 137.363µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T10:05:50.436561Z","caller":"traceutil/trace.go:171","msg":"trace[1483769331] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"249.605889ms","start":"2026-02-28T10:05:50.186909Z","end":"2026-02-28T10:05:50.436515Z","steps":["trace[1483769331] 'process raft request' (duration: 249.401374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:50.436645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.4587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-28T10:05:50.436658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.248875ms","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-28T10:05:50.436685Z","caller":"traceutil/trace.go:171","msg":"trace[1714616147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"248.547443ms","start":"2026-02-28T10:05:50.188122Z","end":"2026-02-28T10:05:50.43667Z","steps":["trace[1714616147] 'agreement among raft nodes before linearized reading' (duration: 248.454081ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:50.436711Z","caller":"traceutil/trace.go:171","msg":"trace[1812146258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"222.309067ms","start":"2026-02-28T10:05:50.214387Z","end":"2026-02-28T10:05:50.436696Z","steps":["trace[1812146258] 'agreement among raft nodes before linearized reading' (duration: 222.210064ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:50.811824Z","caller":"traceutil/trace.go:171","msg":"trace[757594395] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"369.888191ms","start":"2026-02-28T10:05:50.441912Z","end":"2026-02-28T10:05:50.8118Z","steps":["trace[757594395] 'process raft request' (duration: 369.83676ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:50.811866Z","caller":"traceutil/trace.go:171","msg":"trace[1648618320] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"371.115312ms","start":"2026-02-28T10:05:50.440719Z","end":"2026-02-28T10:05:50.811834Z","steps":["trace[1648618320] 'process raft request' (duration: 369.283476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:50.811988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:50.441889Z","time spent":"370.009163ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T10:05:50.81208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:50.4407Z","time spent":"371.238335ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T10:05:57.492539Z","caller":"traceutil/trace.go:171","msg":"trace[329197793] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"521.61605ms","start":"2026-02-28T10:05:56.970894Z","end":"2026-02-28T10:05:57.49251Z","steps":["trace[329197793] 'process raft request' (duration: 521.431075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:57.492734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:56.970865Z","time spent":"521.772434ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T10:05:58.28213Z","caller":"traceutil/trace.go:171","msg":"trace[1807514867] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"763.849103ms","start":"2026-02-28T10:05:57.517753Z","end":"2026-02-28T10:05:58.281602Z","steps":["trace[1807514867] 'process raft request' (duration: 763.520495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:58.282281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:57.517731Z","time spent":"764.470849ms","remote":"127.0.0.1:47636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4744,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T10:05:58.318879Z","caller":"traceutil/trace.go:171","msg":"trace[90280544] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:547; }","duration":"298.487663ms","start":"2026-02-28T10:05:58.020295Z","end":"2026-02-28T10:05:58.318783Z","steps":["trace[90280544] 'read index received' (duration: 261.281732ms)","trace[90280544] 'applied index is now lower than readState.Index' (duration: 37.204661ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:05:58.319068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.74945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T10:05:58.319107Z","caller":"traceutil/trace.go:171","msg":"trace[2001616783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"298.834202ms","start":"2026-02-28T10:05:58.020261Z","end":"2026-02-28T10:05:58.319096Z","steps":["trace[2001616783] 'agreement among raft nodes before linearized reading' (duration: 298.747479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:58.319151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.937765ms","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-28T10:05:58.319229Z","caller":"traceutil/trace.go:171","msg":"trace[1280047191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"186.021327ms","start":"2026-02-28T10:05:58.133188Z","end":"2026-02-28T10:05:58.31921Z","steps":["trace[1280047191] 'agreement among raft nodes before linearized reading' (duration: 185.864543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:58.319145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.895572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T10:05:58.31937Z","caller":"traceutil/trace.go:171","msg":"trace[771445677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"110.144818ms","start":"2026-02-28T10:05:58.209182Z","end":"2026-02-28T10:05:58.319327Z","steps":["trace[771445677] 'agreement among raft nodes before linearized reading' (duration: 109.898592ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:58.319318Z","caller":"traceutil/trace.go:171","msg":"trace[2080627971] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"781.99539ms","start":"2026-02-28T10:05:57.536939Z","end":"2026-02-28T10:05:58.318934Z","steps":["trace[2080627971] 'process raft request' (duration: 781.683341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:58.319652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:57.53691Z","time spent":"782.666275ms","remote":"127.0.0.1:47704","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-28T10:05:58.499355Z","caller":"traceutil/trace.go:171","msg":"trace[293602553] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"175.658389ms","start":"2026-02-28T10:05:58.323662Z","end":"2026-02-28T10:05:58.49932Z","steps":["trace[293602553] 'process raft request' (duration: 111.309047ms)","trace[293602553] 'compare' (duration: 64.127806ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:05:59.181888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.287406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T10:05:59.182037Z","caller":"traceutil/trace.go:171","msg":"trace[1105543917] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"677.361809ms","start":"2026-02-28T10:05:58.504636Z","end":"2026-02-28T10:05:59.181998Z","steps":["trace[1105543917] 'process raft request' (duration: 456.855398ms)","trace[1105543917] 'compare' (duration: 220.10958ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:05:59.182184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:58.504613Z","time spent":"677.483472ms","remote":"127.0.0.1:47704","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-28T10:05:59.182242Z","caller":"traceutil/trace.go:171","msg":"trace[1118614968] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"294.820612ms","start":"2026-02-28T10:05:58.88739Z","end":"2026-02-28T10:05:59.182211Z","steps":["trace[1118614968] 'read index received' (duration: 74.006813ms)","trace[1118614968] 'applied index is now lower than readState.Index' (duration: 220.810679ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:05:59.234421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.37633ms","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-28T10:05:59.234484Z","caller":"traceutil/trace.go:171","msg":"trace[952714350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"302.460243ms","start":"2026-02-28T10:05:58.932008Z","end":"2026-02-28T10:05:59.234468Z","steps":["trace[952714350] 'agreement among raft nodes before linearized reading' (duration: 302.35451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:59.234413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.003698ms","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-28T10:05:59.234522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:58.931997Z","time spent":"302.516564ms","remote":"127.0.0.1:47704","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-28T10:05:59.234587Z","caller":"traceutil/trace.go:171","msg":"trace[688786654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"347.230873ms","start":"2026-02-28T10:05:58.887336Z","end":"2026-02-28T10:05:59.234567Z","steps":["trace[688786654] 'agreement among raft nodes before linearized reading' (duration: 295.003176ms)","trace[688786654] 'range keys from in-memory index tree' (duration: 51.981321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:05:59.234643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:58.887297Z","time spent":"347.333576ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-28T10:05:59.519867Z","caller":"traceutil/trace.go:171","msg":"trace[777646065] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"280.565744ms","start":"2026-02-28T10:05:59.239276Z","end":"2026-02-28T10:05:59.519842Z","steps":["trace[777646065] 'process raft request' (duration: 280.393799ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:05:59.76129Z","caller":"traceutil/trace.go:171","msg":"trace[1315668081] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"521.386963ms","start":"2026-02-28T10:05:59.239655Z","end":"2026-02-28T10:05:59.761042Z","steps":["trace[1315668081] 'process raft request' (duration: 521.26234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:05:59.761486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:05:59.239633Z","time spent":"521.744153ms","remote":"127.0.0.1:47704","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-28T10:06:01.316837Z","caller":"traceutil/trace.go:171","msg":"trace[692474886] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"146.494809ms","start":"2026-02-28T10:06:01.170303Z","end":"2026-02-28T10:06:01.316798Z","steps":["trace[692474886] 'process raft request' (duration: 146.327594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:01.317011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.778849ms","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-28T10:06:01.317081Z","caller":"traceutil/trace.go:171","msg":"trace[211100159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"131.901122ms","start":"2026-02-28T10:06:01.185166Z","end":"2026-02-28T10:06:01.317067Z","steps":["trace[211100159] 'agreement among raft nodes before linearized reading' (duration: 131.682237ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:06:01.316793Z","caller":"traceutil/trace.go:171","msg":"trace[1318511087] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"131.543703ms","start":"2026-02-28T10:06:01.185204Z","end":"2026-02-28T10:06:01.316748Z","steps":["trace[1318511087] 'read index received' (duration: 131.382379ms)","trace[1318511087] 'applied index is now lower than readState.Index' (duration: 160.214µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:01.317913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.684811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T10:06:01.318639Z","caller":"traceutil/trace.go:171","msg":"trace[668094559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"109.45224ms","start":"2026-02-28T10:06:01.209162Z","end":"2026-02-28T10:06:01.318614Z","steps":["trace[668094559] 'agreement among raft nodes before linearized reading' (duration: 108.690192ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:06:18.067891Z","caller":"traceutil/trace.go:171","msg":"trace[117922385] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"217.123235ms","start":"2026-02-28T10:06:17.85074Z","end":"2026-02-28T10:06:18.067863Z","steps":["trace[117922385] 'process raft request' (duration: 216.91166ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:06:18.960703Z","caller":"traceutil/trace.go:171","msg":"trace[827357807] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"371.505472ms","start":"2026-02-28T10:06:18.589175Z","end":"2026-02-28T10:06:18.960681Z","steps":["trace[827357807] 'read index received' (duration: 371.325307ms)","trace[827357807] 'applied index is now lower than readState.Index' (duration: 179.234µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:18.96089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.691636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-28T10:06:18.960927Z","caller":"traceutil/trace.go:171","msg":"trace[1100808659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:717; }","duration":"371.767538ms","start":"2026-02-28T10:06:18.589149Z","end":"2026-02-28T10:06:18.960916Z","steps":["trace[1100808659] 'agreement among raft nodes before linearized reading' (duration: 371.617515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:18.960966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:18.589137Z","time spent":"371.820519ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-28T10:06:18.960959Z","caller":"traceutil/trace.go:171","msg":"trace[818140917] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"454.54948ms","start":"2026-02-28T10:06:18.506387Z","end":"2026-02-28T10:06:18.960937Z","steps":["trace[818140917] 'process raft request' (duration: 454.13852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:18.961069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:18.506364Z","time spent":"454.646283ms","remote":"127.0.0.1:47704","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-28T10:06:19.451528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.733911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T10:06:19.452533Z","caller":"traceutil/trace.go:171","msg":"trace[556620487] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"487.932717ms","start":"2026-02-28T10:06:18.96455Z","end":"2026-02-28T10:06:19.452483Z","steps":["trace[556620487] 'process raft request' (duration: 273.152609ms)","trace[556620487] 'compare' (duration: 213.506256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:19.452767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:18.964529Z","time spent":"488.100562ms","remote":"127.0.0.1:47704","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-28T10:06:19.837696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.057255ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-28T10:06:19.837777Z","caller":"traceutil/trace.go:171","msg":"trace[1443574382] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:742; }","duration":"763.111645ms","start":"2026-02-28T10:06:19.074648Z","end":"2026-02-28T10:06:19.83776Z","steps":["trace[1443574382] 'read index received' (duration: 163.095713ms)","trace[1443574382] 'applied index is now lower than readState.Index' (duration: 600.014842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:19.838094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.435703ms","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-28T10:06:19.838136Z","caller":"traceutil/trace.go:171","msg":"trace[571524501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"763.511925ms","start":"2026-02-28T10:06:19.074613Z","end":"2026-02-28T10:06:19.838125Z","steps":["trace[571524501] 'agreement among raft nodes before linearized reading' (duration: 763.366712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:19.838168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:19.074592Z","time spent":"763.566416ms","remote":"127.0.0.1:47704","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-28T10:06:19.838206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.288745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T10:06:19.838251Z","caller":"traceutil/trace.go:171","msg":"trace[245045458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"629.362906ms","start":"2026-02-28T10:06:19.208876Z","end":"2026-02-28T10:06:19.838239Z","steps":["trace[245045458] 'agreement among raft nodes before linearized reading' (duration: 629.284024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:19.838273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:19.208863Z","time spent":"629.406447ms","remote":"127.0.0.1:47470","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-28T10:06:19.83828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.473326ms","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-28T10:06:19.838344Z","caller":"traceutil/trace.go:171","msg":"trace[1184864984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"235.542847ms","start":"2026-02-28T10:06:19.602784Z","end":"2026-02-28T10:06:19.838327Z","steps":["trace[1184864984] 'agreement among raft nodes before linearized reading' (duration: 235.374173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:19.838425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.155945ms","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-28T10:06:19.838444Z","caller":"traceutil/trace.go:171","msg":"trace[2115342920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"443.210766ms","start":"2026-02-28T10:06:19.395229Z","end":"2026-02-28T10:06:19.83844Z","steps":["trace[2115342920] 'agreement among raft nodes before linearized reading' (duration: 443.138524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:19.838456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:19.395209Z","time spent":"443.244607ms","remote":"127.0.0.1:47704","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":"info","ts":"2026-02-28T10:06:19.998505Z","caller":"traceutil/trace.go:171","msg":"trace[611914083] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"155.852191ms","start":"2026-02-28T10:06:19.842634Z","end":"2026-02-28T10:06:19.998486Z","steps":["trace[611914083] 'process raft request' (duration: 155.81362ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:06:19.998538Z","caller":"traceutil/trace.go:171","msg":"trace[1110346228] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"156.460216ms","start":"2026-02-28T10:06:19.84205Z","end":"2026-02-28T10:06:19.99851Z","steps":["trace[1110346228] 'process raft request' (duration: 156.278152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:20.911457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.0665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T10:06:20.911531Z","caller":"traceutil/trace.go:171","msg":"trace[527998732] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"836.528232ms","start":"2026-02-28T10:06:20.074989Z","end":"2026-02-28T10:06:20.911518Z","steps":["trace[527998732] 'process raft request' (duration: 36.32719ms)","trace[527998732] 'compare' (duration: 799.992987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:20.911569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:20.07497Z","time spent":"836.582243ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T10:06:21.431375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.301582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T10:06:21.431453Z","caller":"traceutil/trace.go:171","msg":"trace[445973880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"223.388384ms","start":"2026-02-28T10:06:21.208046Z","end":"2026-02-28T10:06:21.431435Z","steps":["trace[445973880] 'range keys from in-memory index tree' (duration: 223.20855ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:06:41.703045Z","caller":"traceutil/trace.go:171","msg":"trace[1226034118] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"109.781781ms","start":"2026-02-28T10:06:41.593239Z","end":"2026-02-28T10:06:41.703021Z","steps":["trace[1226034118] 'process raft request' (duration: 109.618047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:42.406941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.738523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-28T10:06:42.407025Z","caller":"traceutil/trace.go:171","msg":"trace[1869359032] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"314.899227ms","start":"2026-02-28T10:06:42.092107Z","end":"2026-02-28T10:06:42.407006Z","steps":["trace[1869359032] 'range keys from in-memory index tree' (duration: 314.552318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:42.407007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.134067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-28T10:06:42.407135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:42.092096Z","time spent":"314.998239ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T10:06:42.407388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.668237ms","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-28T10:06:42.407585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.454054ms","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-28T10:06:42.407708Z","caller":"traceutil/trace.go:171","msg":"trace[990465514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"361.588767ms","start":"2026-02-28T10:06:42.046099Z","end":"2026-02-28T10:06:42.407688Z","steps":["trace[990465514] 'range keys from in-memory index tree' (duration: 361.249229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:42.407761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:42.046037Z","time spent":"361.71078ms","remote":"127.0.0.1:47704","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-28T10:06:42.407465Z","caller":"traceutil/trace.go:171","msg":"trace[107291983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"313.79369ms","start":"2026-02-28T10:06:42.093652Z","end":"2026-02-28T10:06:42.407445Z","steps":["trace[107291983] 'range keys from in-memory index tree' (duration: 313.417961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:42.407944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:42.093632Z","time spent":"314.293382ms","remote":"127.0.0.1:47704","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-28T10:06:42.407962Z","caller":"traceutil/trace.go:171","msg":"trace[1443314693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"197.25114ms","start":"2026-02-28T10:06:42.209845Z","end":"2026-02-28T10:06:42.407096Z","steps":["trace[1443314693] 'range keys from in-memory index tree' (duration: 197.064326ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:06:42.81147Z","caller":"traceutil/trace.go:171","msg":"trace[1951474429] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"398.460115ms","start":"2026-02-28T10:06:42.412991Z","end":"2026-02-28T10:06:42.811451Z","steps":["trace[1951474429] 'process raft request' (duration: 398.343372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:42.811597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:42.412972Z","time spent":"398.550436ms","remote":"127.0.0.1:47704","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-28T10:06:43.071782Z","caller":"traceutil/trace.go:171","msg":"trace[2106542071] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"658.651232ms","start":"2026-02-28T10:06:42.413103Z","end":"2026-02-28T10:06:43.071754Z","steps":["trace[2106542071] 'process raft request' (duration: 658.435237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:43.071941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:42.413097Z","time spent":"658.765635ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T10:06:43.071898Z","caller":"traceutil/trace.go:171","msg":"trace[1276023352] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"658.708644ms","start":"2026-02-28T10:06:42.41316Z","end":"2026-02-28T10:06:43.071869Z","steps":["trace[1276023352] 'process raft request' (duration: 658.486839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:43.072892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:42.413146Z","time spent":"659.497452ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T10:06:45.026159Z","caller":"traceutil/trace.go:171","msg":"trace[2048964066] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"206.401737ms","start":"2026-02-28T10:06:44.819716Z","end":"2026-02-28T10:06:45.026118Z","steps":["trace[2048964066] 'process raft request' (duration: 206.246714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:45.493281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.831922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T10:06:45.493356Z","caller":"traceutil/trace.go:171","msg":"trace[1668416731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"284.945126ms","start":"2026-02-28T10:06:45.208393Z","end":"2026-02-28T10:06:45.493338Z","steps":["trace[1668416731] 'range keys from in-memory index tree' (duration: 284.760911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:45.493396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.956697ms","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-28T10:06:45.493441Z","caller":"traceutil/trace.go:171","msg":"trace[909696867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"357.104261ms","start":"2026-02-28T10:06:45.136328Z","end":"2026-02-28T10:06:45.493432Z","steps":["trace[909696867] 'range keys from in-memory index tree' (duration: 356.833535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:45.493469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.385324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-28T10:06:45.493512Z","caller":"traceutil/trace.go:171","msg":"trace[466463803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"417.443736ms","start":"2026-02-28T10:06:45.076057Z","end":"2026-02-28T10:06:45.4935Z","steps":["trace[466463803] 'range keys from in-memory index tree' (duration: 417.288912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:45.493479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:45.136307Z","time spent":"357.163523ms","remote":"127.0.0.1:47704","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-28T10:06:45.493543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:45.07605Z","time spent":"417.485127ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T10:06:45.493495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.453327ms","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-28T10:06:45.49361Z","caller":"traceutil/trace.go:171","msg":"trace[1799110958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"417.655431ms","start":"2026-02-28T10:06:45.07593Z","end":"2026-02-28T10:06:45.493586Z","steps":["trace[1799110958] 'range keys from in-memory index tree' (duration: 417.20641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:45.493661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:45.075912Z","time spent":"417.738843ms","remote":"127.0.0.1:47704","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-28T10:06:45.992878Z","caller":"traceutil/trace.go:171","msg":"trace[564493950] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"496.074158ms","start":"2026-02-28T10:06:45.49678Z","end":"2026-02-28T10:06:45.992854Z","steps":["trace[564493950] 'process raft request' (duration: 495.939815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:45.993094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:45.496762Z","time spent":"496.183ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T10:06:46.080511Z","caller":"traceutil/trace.go:171","msg":"trace[1328927806] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"276.733091ms","start":"2026-02-28T10:06:45.803758Z","end":"2026-02-28T10:06:46.080491Z","steps":["trace[1328927806] 'read index received' (duration: 189.107828ms)","trace[1328927806] 'applied index is now lower than readState.Index' (duration: 87.624383ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T10:06:46.080573Z","caller":"traceutil/trace.go:171","msg":"trace[71482433] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"583.3059ms","start":"2026-02-28T10:06:45.497236Z","end":"2026-02-28T10:06:46.080542Z","steps":["trace[71482433] 'process raft request' (duration: 521.440839ms)","trace[71482433] 'compare' (duration: 60.984521ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:46.08067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.906935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-28T10:06:46.080614Z","caller":"traceutil/trace.go:171","msg":"trace[84761644] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"583.150287ms","start":"2026-02-28T10:06:45.49743Z","end":"2026-02-28T10:06:46.080581Z","steps":["trace[84761644] 'process raft request' (duration: 583.005454ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:06:46.080734Z","caller":"traceutil/trace.go:171","msg":"trace[1400202076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"276.995137ms","start":"2026-02-28T10:06:45.803703Z","end":"2026-02-28T10:06:46.080698Z","steps":["trace[1400202076] 'agreement among raft nodes before linearized reading' (duration: 276.863884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:46.080844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:45.497421Z","time spent":"583.350102ms","remote":"127.0.0.1:47704","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-28T10:06:46.080733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:45.497222Z","time spent":"583.419473ms","remote":"127.0.0.1:47704","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-28T10:06:56.150398Z","caller":"traceutil/trace.go:171","msg":"trace[1857079239] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"118.576869ms","start":"2026-02-28T10:06:56.031801Z","end":"2026-02-28T10:06:56.150378Z","steps":["trace[1857079239] 'process raft request' (duration: 118.311883ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:06:58.30855Z","caller":"traceutil/trace.go:171","msg":"trace[1256219948] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"146.706019ms","start":"2026-02-28T10:06:58.161813Z","end":"2026-02-28T10:06:58.308519Z","steps":["trace[1256219948] 'read index received' (duration: 146.443793ms)","trace[1256219948] 'applied index is now lower than readState.Index' (duration: 261.156µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:58.308869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.019897ms","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-28T10:06:58.308917Z","caller":"traceutil/trace.go:171","msg":"trace[2100871586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"147.102548ms","start":"2026-02-28T10:06:58.161798Z","end":"2026-02-28T10:06:58.308901Z","steps":["trace[2100871586] 'agreement among raft nodes before linearized reading' (duration: 146.868323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:58.308946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.806101ms","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-28T10:06:58.309023Z","caller":"traceutil/trace.go:171","msg":"trace[1936600856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"146.913713ms","start":"2026-02-28T10:06:58.162092Z","end":"2026-02-28T10:06:58.309006Z","steps":["trace[1936600856] 'agreement among raft nodes before linearized reading' (duration: 146.644597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:58.309207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.640769ms","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-28T10:06:58.309232Z","caller":"traceutil/trace.go:171","msg":"trace[1368747656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"144.675891ms","start":"2026-02-28T10:06:58.164548Z","end":"2026-02-28T10:06:58.309224Z","steps":["trace[1368747656] 'agreement among raft nodes before linearized reading' (duration: 144.599809ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:06:58.30956Z","caller":"traceutil/trace.go:171","msg":"trace[318360845] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"151.017101ms","start":"2026-02-28T10:06:58.157657Z","end":"2026-02-28T10:06:58.308674Z","steps":["trace[318360845] 'process raft request' (duration: 150.689473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:58.941112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.792316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T10:06:58.941498Z","caller":"traceutil/trace.go:171","msg":"trace[868298043] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"628.073974ms","start":"2026-02-28T10:06:58.313405Z","end":"2026-02-28T10:06:58.941479Z","steps":["trace[868298043] 'process raft request' (duration: 124.840168ms)","trace[868298043] 'compare' (duration: 502.646543ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:58.941583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:58.31338Z","time spent":"628.165337ms","remote":"127.0.0.1:47704","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-28T10:06:58.942023Z","caller":"traceutil/trace.go:171","msg":"trace[1180582592] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"628.050674ms","start":"2026-02-28T10:06:58.313948Z","end":"2026-02-28T10:06:58.941999Z","steps":["trace[1180582592] 'process raft request' (duration: 627.496741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:58.942139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:58.313934Z","time spent":"628.141256ms","remote":"127.0.0.1:47704","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-28T10:06:58.942391Z","caller":"traceutil/trace.go:171","msg":"trace[790582508] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"628.342551ms","start":"2026-02-28T10:06:58.314041Z","end":"2026-02-28T10:06:58.942384Z","steps":["trace[790582508] 'process raft request' (duration: 627.89545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:06:58.942436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:58.314034Z","time spent":"628.380161ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T10:06:58.942568Z","caller":"traceutil/trace.go:171","msg":"trace[1800104291] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:914; }","duration":"589.961528ms","start":"2026-02-28T10:06:58.3526Z","end":"2026-02-28T10:06:58.942561Z","steps":["trace[1800104291] 'read index received' (duration: 85.626276ms)","trace[1800104291] 'applied index is now lower than readState.Index' (duration: 504.334372ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:58.966201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.59335ms","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-28T10:06:58.966246Z","caller":"traceutil/trace.go:171","msg":"trace[1481861344] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:881; }","duration":"613.657312ms","start":"2026-02-28T10:06:58.352578Z","end":"2026-02-28T10:06:58.966235Z","steps":["trace[1481861344] 'agreement among raft nodes before linearized reading' (duration: 590.005809ms)","trace[1481861344] 'count revisions from in-memory index tree' (duration: 23.588202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:06:58.966271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:06:58.352569Z","time spent":"613.695963ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-28T10:07:11.277569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.802984ms","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-28T10:07:11.277742Z","caller":"traceutil/trace.go:171","msg":"trace[669948530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"119.03105ms","start":"2026-02-28T10:07:11.15869Z","end":"2026-02-28T10:07:11.277721Z","steps":["trace[669948530] 'range keys from in-memory index tree' (duration: 118.56747ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:13.52105Z","caller":"traceutil/trace.go:171","msg":"trace[603724358] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"179.046858ms","start":"2026-02-28T10:07:13.341976Z","end":"2026-02-28T10:07:13.521023Z","steps":["trace[603724358] 'process raft request' (duration: 178.866584ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:13.721931Z","caller":"traceutil/trace.go:171","msg":"trace[264008865] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"192.498458ms","start":"2026-02-28T10:07:13.529413Z","end":"2026-02-28T10:07:13.721911Z","steps":["trace[264008865] 'process raft request' (duration: 117.174677ms)","trace[264008865] 'compare' (duration: 75.237669ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T10:07:14.852915Z","caller":"traceutil/trace.go:171","msg":"trace[1250173487] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"155.114199ms","start":"2026-02-28T10:07:14.697775Z","end":"2026-02-28T10:07:14.85289Z","steps":["trace[1250173487] 'read index received' (duration: 154.879164ms)","trace[1250173487] 'applied index is now lower than readState.Index' (duration: 234.115µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:07:14.853094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.302983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T10:07:14.853091Z","caller":"traceutil/trace.go:171","msg":"trace[1130705906] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"459.444985ms","start":"2026-02-28T10:07:14.39361Z","end":"2026-02-28T10:07:14.853055Z","steps":["trace[1130705906] 'process raft request' (duration: 459.109187ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:14.853146Z","caller":"traceutil/trace.go:171","msg":"trace[805733971] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:933; }","duration":"155.407346ms","start":"2026-02-28T10:07:14.697717Z","end":"2026-02-28T10:07:14.853125Z","steps":["trace[805733971] 'agreement among raft nodes before linearized reading' (duration: 155.269863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:14.853256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:14.393594Z","time spent":"459.581398ms","remote":"127.0.0.1:47704","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-28T10:07:16.197824Z","caller":"traceutil/trace.go:171","msg":"trace[129484359] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"153.07632ms","start":"2026-02-28T10:07:16.044721Z","end":"2026-02-28T10:07:16.197797Z","steps":["trace[129484359] 'process raft request' (duration: 152.945737ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:19.763207Z","caller":"traceutil/trace.go:171","msg":"trace[798909423] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"214.593642ms","start":"2026-02-28T10:07:19.548591Z","end":"2026-02-28T10:07:19.763184Z","steps":["trace[798909423] 'process raft request' (duration: 214.451248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:20.24773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.645099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-28T10:07:20.247823Z","caller":"traceutil/trace.go:171","msg":"trace[184808607] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:979; }","duration":"255.752092ms","start":"2026-02-28T10:07:19.992049Z","end":"2026-02-28T10:07:20.247801Z","steps":["trace[184808607] 'range keys from in-memory index tree' (duration: 255.476025ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:21.905869Z","caller":"traceutil/trace.go:171","msg":"trace[1418962173] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"548.023482ms","start":"2026-02-28T10:07:21.357823Z","end":"2026-02-28T10:07:21.905846Z","steps":["trace[1418962173] 'process raft request' (duration: 547.823237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:21.906005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:21.357798Z","time spent":"548.141684ms","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T10:07:22.23013Z","caller":"traceutil/trace.go:171","msg":"trace[973634907] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1028; }","duration":"462.800044ms","start":"2026-02-28T10:07:21.767307Z","end":"2026-02-28T10:07:22.230107Z","steps":["trace[973634907] 'read index received' (duration: 138.431961ms)","trace[973634907] 'applied index is now lower than readState.Index' (duration: 324.367153ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T10:07:22.230234Z","caller":"traceutil/trace.go:171","msg":"trace[865185992] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"868.41409ms","start":"2026-02-28T10:07:21.361809Z","end":"2026-02-28T10:07:22.230223Z","steps":["trace[865185992] 'process raft request' (duration: 828.422269ms)","trace[865185992] 'compare' (duration: 39.492659ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:07:22.230501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:21.361792Z","time spent":"868.468742ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-28T10:07:22.230867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.611973ms","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-28T10:07:22.231007Z","caller":"traceutil/trace.go:171","msg":"trace[1102325696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:989; }","duration":"463.790618ms","start":"2026-02-28T10:07:21.767189Z","end":"2026-02-28T10:07:22.230979Z","steps":["trace[1102325696] 'agreement among raft nodes before linearized reading' (duration: 463.374588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:22.231098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:21.76717Z","time spent":"463.912471ms","remote":"127.0.0.1:47704","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-28T10:07:22.675869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.553962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T10:07:22.67628Z","caller":"traceutil/trace.go:171","msg":"trace[1170628378] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"766.598929ms","start":"2026-02-28T10:07:21.909646Z","end":"2026-02-28T10:07:22.676245Z","steps":["trace[1170628378] 'process raft request' (duration: 625.600876ms)","trace[1170628378] 'compare' (duration: 140.45602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:07:22.676532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.950111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-28T10:07:22.676545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.559011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-28T10:07:22.676608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.907322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-28T10:07:22.676614Z","caller":"traceutil/trace.go:171","msg":"trace[221575162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"468.666754ms","start":"2026-02-28T10:07:22.207931Z","end":"2026-02-28T10:07:22.676597Z","steps":["trace[221575162] 'agreement among raft nodes before linearized reading' (duration: 468.460859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:22.676535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:21.909622Z","time spent":"766.838094ms","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":860,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T10:07:22.676672Z","caller":"traceutil/trace.go:171","msg":"trace[599377049] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:991; }","duration":"728.987634ms","start":"2026-02-28T10:07:21.947655Z","end":"2026-02-28T10:07:22.676643Z","steps":["trace[599377049] 'agreement among raft nodes before linearized reading' (duration: 728.780259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:22.676676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:22.207911Z","time spent":"468.752986ms","remote":"127.0.0.1:47466","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-28T10:07:22.67667Z","caller":"traceutil/trace.go:171","msg":"trace[1371599230] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:991; }","duration":"580.050822ms","start":"2026-02-28T10:07:22.096527Z","end":"2026-02-28T10:07:22.676578Z","steps":["trace[1371599230] 'agreement among raft nodes before linearized reading' (duration: 579.883328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:22.676715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:21.947643Z","time spent":"729.060965ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-28T10:07:22.676393Z","caller":"traceutil/trace.go:171","msg":"trace[766592829] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"446.173519ms","start":"2026-02-28T10:07:22.230195Z","end":"2026-02-28T10:07:22.676368Z","steps":["trace[766592829] 'read index received' (duration: 305.007363ms)","trace[766592829] 'applied index is now lower than readState.Index' (duration: 141.162746ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:07:22.676736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:22.096512Z","time spent":"580.207078ms","remote":"127.0.0.1:47748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-28T10:07:22.67676Z","caller":"traceutil/trace.go:171","msg":"trace[511050730] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"441.10981ms","start":"2026-02-28T10:07:22.235616Z","end":"2026-02-28T10:07:22.676726Z","steps":["trace[511050730] 'process raft request' (duration: 440.623487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:22.676872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:22.235602Z","time spent":"441.210242ms","remote":"127.0.0.1:47704","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-28T10:07:22.677369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.96728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-28T10:07:22.677429Z","caller":"traceutil/trace.go:171","msg":"trace[1604824892] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:992; }","duration":"442.023571ms","start":"2026-02-28T10:07:22.235389Z","end":"2026-02-28T10:07:22.677413Z","steps":["trace[1604824892] 'agreement among raft nodes before linearized reading' (duration: 441.868627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:22.677471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:22.235319Z","time spent":"442.140534ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-28T10:07:22.677612Z","caller":"traceutil/trace.go:171","msg":"trace[1961566245] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"441.803356ms","start":"2026-02-28T10:07:22.235789Z","end":"2026-02-28T10:07:22.677592Z","steps":["trace[1961566245] 'process raft request' (duration: 440.653658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:22.678628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:22.235781Z","time spent":"441.937739ms","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":910,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T10:07:22.785107Z","caller":"traceutil/trace.go:171","msg":"trace[844617206] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"103.708586ms","start":"2026-02-28T10:07:22.681355Z","end":"2026-02-28T10:07:22.785063Z","steps":["trace[844617206] 'process raft request' (duration: 96.138676ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:22.791548Z","caller":"traceutil/trace.go:171","msg":"trace[1026621834] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"109.702058ms","start":"2026-02-28T10:07:22.681808Z","end":"2026-02-28T10:07:22.79151Z","steps":["trace[1026621834] 'process raft request' (duration: 109.460413ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:23.258087Z","caller":"traceutil/trace.go:171","msg":"trace[816337007] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"137.143021ms","start":"2026-02-28T10:07:23.120885Z","end":"2026-02-28T10:07:23.258028Z","steps":["trace[816337007] 'process raft request' (duration: 136.869474ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:23.258358Z","caller":"traceutil/trace.go:171","msg":"trace[1160063516] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"135.588204ms","start":"2026-02-28T10:07:23.122746Z","end":"2026-02-28T10:07:23.258334Z","steps":["trace[1160063516] 'process raft request' (duration: 135.234306ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:23.258702Z","caller":"traceutil/trace.go:171","msg":"trace[1102470205] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"138.095573ms","start":"2026-02-28T10:07:23.120046Z","end":"2026-02-28T10:07:23.258142Z","steps":["trace[1102470205] 'process raft request' (duration: 137.879588ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:23.25911Z","caller":"traceutil/trace.go:171","msg":"trace[807067991] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1038; }","duration":"135.791369ms","start":"2026-02-28T10:07:23.123271Z","end":"2026-02-28T10:07:23.259063Z","steps":["trace[807067991] 'read index received' (duration: 85.818622ms)","trace[807067991] 'applied index is now lower than readState.Index' (duration: 49.971907ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T10:07:23.259274Z","caller":"traceutil/trace.go:171","msg":"trace[1087466703] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"130.931172ms","start":"2026-02-28T10:07:23.128324Z","end":"2026-02-28T10:07:23.259255Z","steps":["trace[1087466703] 'process raft request' (duration: 129.768245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:23.259448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.158968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-02-28T10:07:23.259521Z","caller":"traceutil/trace.go:171","msg":"trace[1376801268] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1002; }","duration":"136.24062ms","start":"2026-02-28T10:07:23.123264Z","end":"2026-02-28T10:07:23.259505Z","steps":["trace[1376801268] 'agreement among raft nodes before linearized reading' (duration: 136.041705ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:24.012744Z","caller":"traceutil/trace.go:171","msg":"trace[867294354] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"183.346169ms","start":"2026-02-28T10:07:23.829376Z","end":"2026-02-28T10:07:24.012722Z","steps":["trace[867294354] 'read index received' (duration: 183.121334ms)","trace[867294354] 'applied index is now lower than readState.Index' (duration: 223.555µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:07:24.012781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:23.522454Z","time spent":"490.319549ms","remote":"127.0.0.1:47498","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-28T10:07:24.012875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.485273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T10:07:24.012913Z","caller":"traceutil/trace.go:171","msg":"trace[344616714] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1005; }","duration":"183.594165ms","start":"2026-02-28T10:07:23.829309Z","end":"2026-02-28T10:07:24.012903Z","steps":["trace[344616714] 'agreement among raft nodes before linearized reading' (duration: 183.504463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:24.709521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360580484122539,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-28T10:07:24.941434Z","caller":"traceutil/trace.go:171","msg":"trace[286690561] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"821.372891ms","start":"2026-02-28T10:07:24.120032Z","end":"2026-02-28T10:07:24.941405Z","steps":["trace[286690561] 'process raft request' (duration: 821.221978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:24.94154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.069527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-28T10:07:24.941567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:24.120012Z","time spent":"821.496414ms","remote":"127.0.0.1:47546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":795,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T10:07:24.941577Z","caller":"traceutil/trace.go:171","msg":"trace[1880149887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"732.147679ms","start":"2026-02-28T10:07:24.209419Z","end":"2026-02-28T10:07:24.941567Z","steps":["trace[1880149887] 'agreement among raft nodes before linearized reading' (duration: 732.054237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:24.941608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:24.209403Z","time spent":"732.199721ms","remote":"127.0.0.1:47470","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-28T10:07:24.941431Z","caller":"traceutil/trace.go:171","msg":"trace[1730256500] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"731.928984ms","start":"2026-02-28T10:07:24.209458Z","end":"2026-02-28T10:07:24.941387Z","steps":["trace[1730256500] 'read index received' (duration: 731.75108ms)","trace[1730256500] 'applied index is now lower than readState.Index' (duration: 176.844µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:07:24.942079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.71636ms","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-28T10:07:24.94213Z","caller":"traceutil/trace.go:171","msg":"trace[1246420065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"260.804542ms","start":"2026-02-28T10:07:24.681311Z","end":"2026-02-28T10:07:24.942115Z","steps":["trace[1246420065] 'agreement among raft nodes before linearized reading' (duration: 260.638208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:25.284313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.304005ms","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-28T10:07:25.284434Z","caller":"traceutil/trace.go:171","msg":"trace[1455807940] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1009; }","duration":"272.457708ms","start":"2026-02-28T10:07:25.011959Z","end":"2026-02-28T10:07:25.284417Z","steps":["trace[1455807940] 'range keys from in-memory index tree' (duration: 272.282444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:25.284442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.129075ms","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-28T10:07:25.284504Z","caller":"traceutil/trace.go:171","msg":"trace[922599815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"281.253417ms","start":"2026-02-28T10:07:25.003234Z","end":"2026-02-28T10:07:25.284488Z","steps":["trace[922599815] 'range keys from in-memory index tree' (duration: 280.856438ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:25.439614Z","caller":"traceutil/trace.go:171","msg":"trace[1590317331] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"151.008781ms","start":"2026-02-28T10:07:25.288585Z","end":"2026-02-28T10:07:25.439594Z","steps":["trace[1590317331] 'process raft request' (duration: 150.892148ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:25.583723Z","caller":"traceutil/trace.go:171","msg":"trace[1503259300] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"294.81569ms","start":"2026-02-28T10:07:25.288878Z","end":"2026-02-28T10:07:25.583694Z","steps":["trace[1503259300] 'process raft request' (duration: 294.591154ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:25.584057Z","caller":"traceutil/trace.go:171","msg":"trace[193201641] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"295.135398ms","start":"2026-02-28T10:07:25.288897Z","end":"2026-02-28T10:07:25.584033Z","steps":["trace[193201641] 'process raft request' (duration: 294.707668ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:25.58436Z","caller":"traceutil/trace.go:171","msg":"trace[1459283937] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"295.404414ms","start":"2026-02-28T10:07:25.288939Z","end":"2026-02-28T10:07:25.584344Z","steps":["trace[1459283937] 'process raft request' (duration: 294.995474ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:25.584418Z","caller":"traceutil/trace.go:171","msg":"trace[807665580] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1052; }","duration":"234.932165ms","start":"2026-02-28T10:07:25.349473Z","end":"2026-02-28T10:07:25.584405Z","steps":["trace[807665580] 'read index received' (duration: 90.339208ms)","trace[807665580] 'applied index is now lower than readState.Index' (duration: 144.591957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:07:25.584595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.10687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-02-28T10:07:25.584636Z","caller":"traceutil/trace.go:171","msg":"trace[93671602] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1014; }","duration":"235.197993ms","start":"2026-02-28T10:07:25.349428Z","end":"2026-02-28T10:07:25.584626Z","steps":["trace[93671602] 'agreement among raft nodes before linearized reading' (duration: 235.034638ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:25.584531Z","caller":"traceutil/trace.go:171","msg":"trace[527417912] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"295.398694ms","start":"2026-02-28T10:07:25.289101Z","end":"2026-02-28T10:07:25.584499Z","steps":["trace[527417912] 'process raft request' (duration: 295.184979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:26.162478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.201933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T10:07:26.162549Z","caller":"traceutil/trace.go:171","msg":"trace[879944061] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"219.085749ms","start":"2026-02-28T10:07:25.943453Z","end":"2026-02-28T10:07:26.162539Z","steps":["trace[879944061] 'read index received' (duration: 78.689701ms)","trace[879944061] 'applied index is now lower than readState.Index' (duration: 140.395148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:07:26.162614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.159751ms","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":"info","ts":"2026-02-28T10:07:26.162628Z","caller":"traceutil/trace.go:171","msg":"trace[9884428] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1015; }","duration":"219.209082ms","start":"2026-02-28T10:07:25.943413Z","end":"2026-02-28T10:07:26.162622Z","steps":["trace[9884428] 'agreement among raft nodes before linearized reading' (duration: 219.176632ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:26.162596Z","caller":"traceutil/trace.go:171","msg":"trace[1477440110] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"573.60634ms","start":"2026-02-28T10:07:25.588959Z","end":"2026-02-28T10:07:26.162565Z","steps":["trace[1477440110] 'process raft request' (duration: 433.255473ms)","trace[1477440110] 'compare' (duration: 140.0661ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T10:07:26.162729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T10:07:25.588937Z","time spent":"573.718472ms","remote":"127.0.0.1:47704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T10:07:26.162739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.48887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-wzbpq\" ","response":"range_response_count:1 size:12641"} {"level":"info","ts":"2026-02-28T10:07:26.162796Z","caller":"traceutil/trace.go:171","msg":"trace[1064032387] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-wzbpq; range_end:; response_count:1; response_revision:1015; }","duration":"166.575952ms","start":"2026-02-28T10:07:25.996203Z","end":"2026-02-28T10:07:26.162779Z","steps":["trace[1064032387] 'agreement among raft nodes before linearized reading' (duration: 166.412418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T10:07:27.212603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.657665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T10:07:27.212725Z","caller":"traceutil/trace.go:171","msg":"trace[1654861321] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1021; }","duration":"111.835899ms","start":"2026-02-28T10:07:27.100866Z","end":"2026-02-28T10:07:27.212702Z","steps":["trace[1654861321] 'count revisions from in-memory index tree' (duration: 111.559793ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:27.717935Z","caller":"traceutil/trace.go:171","msg":"trace[1573399181] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"127.093231ms","start":"2026-02-28T10:07:27.590819Z","end":"2026-02-28T10:07:27.717912Z","steps":["trace[1573399181] 'process raft request' (duration: 125.676718ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:27.718816Z","caller":"traceutil/trace.go:171","msg":"trace[858167479] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"127.249205ms","start":"2026-02-28T10:07:27.591515Z","end":"2026-02-28T10:07:27.718764Z","steps":["trace[858167479] 'process raft request' (duration: 126.269652ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:07:27.719474Z","caller":"traceutil/trace.go:171","msg":"trace[1845792499] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"127.602594ms","start":"2026-02-28T10:07:27.591677Z","end":"2026-02-28T10:07:27.71928Z","steps":["trace[1845792499] 'process raft request' (duration: 126.860306ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T10:10:32.687645Z","caller":"traceutil/trace.go:171","msg":"trace[711668326] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"128.430034ms","start":"2026-02-28T10:10:32.559188Z","end":"2026-02-28T10:10:32.687618Z","steps":["trace[711668326] 'process raft request' (duration: 96.749573ms)","trace[711668326] 'compare' (duration: 31.595069ms)"],"step_count":2}