{"level":"warn","ts":"2026-02-25T09:56:51.286089Z","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-25T09:56:51.286216Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12: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.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12: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-25T09:56:51.286308Z","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-25T09:56:51.286326Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-25T09:56:51.286353Z","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-25T09:56:51.287218Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-02-25T09:56:51.287316Z","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.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12: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-25T09:56:51.291296Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.686297ms"} {"level":"info","ts":"2026-02-25T09:56:51.297332Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-02-25T09:56:51.297411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T09:56:51.297434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-02-25T09:56:51.297449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T09:56:51.297458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-02-25T09:56:51.297502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-02-25T09:56:51.308046Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T09:56:51.309472Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T09:56:51.310832Z","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-25T09:56:51.313181Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T09:56:51.31611Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T09:56:51.315997Z","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-25T09:56:51.316375Z","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-25T09:56:51.316447Z","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-25T09:56:51.317289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-02-25T09:56:51.31756Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-02-25T09:56:51.317656Z","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-25T09:56:51.317793Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-25T09:56:51.317832Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-02-25T09:56:51.317924Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T09:56:51.31795Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T09:56:51.497929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T09:56:51.497987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T09:56:51.498026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-02-25T09:56:51.498045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-02-25T09:56:51.498062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-25T09:56:51.498083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-02-25T09:56:51.498102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-02-25T09:56:51.499194Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T09:56:51.499325Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T09:56:51.499446Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T09:56:51.499593Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T09:56:51.499712Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T09:56:51.499974Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T09:56:51.501881Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T09:56:51.502251Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T09:56:51.50235Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T09:56:51.502393Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T09:56:51.503584Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"warn","ts":"2026-02-25T09:56:56.674869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.106529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:56:56.675236Z","caller":"traceutil/trace.go:171","msg":"trace[922760435] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"164.751709ms","start":"2026-02-25T09:56:56.510435Z","end":"2026-02-25T09:56:56.675187Z","steps":["trace[922760435] 'process raft request' (duration: 47.526262ms)","trace[922760435] 'compare' (duration: 115.832927ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:56:56.675327Z","caller":"traceutil/trace.go:171","msg":"trace[264478952] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"159.884745ms","start":"2026-02-25T09:56:56.515403Z","end":"2026-02-25T09:56:56.675288Z","steps":["trace[264478952] 'process raft request' (duration: 159.773134ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:56:56.675349Z","caller":"traceutil/trace.go:171","msg":"trace[1188720720] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"163.091487ms","start":"2026-02-25T09:56:56.512217Z","end":"2026-02-25T09:56:56.675308Z","steps":["trace[1188720720] 'process raft request' (duration: 162.864136ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:56:56.913017Z","caller":"traceutil/trace.go:171","msg":"trace[1892360981] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"144.474239ms","start":"2026-02-25T09:56:56.76852Z","end":"2026-02-25T09:56:56.912994Z","steps":["trace[1892360981] 'read index received' (duration: 88.929181ms)","trace[1892360981] 'applied index is now lower than readState.Index' (duration: 55.541988ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:56:56.913061Z","caller":"traceutil/trace.go:171","msg":"trace[1778077701] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"186.685515ms","start":"2026-02-25T09:56:56.726356Z","end":"2026-02-25T09:56:56.913042Z","steps":["trace[1778077701] 'process raft request' (duration: 131.230036ms)","trace[1778077701] 'compare' (duration: 55.220777ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:56:56.913197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.67047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T09:56:56.913235Z","caller":"traceutil/trace.go:171","msg":"trace[675358329] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:276; }","duration":"144.766552ms","start":"2026-02-25T09:56:56.768457Z","end":"2026-02-25T09:56:56.913224Z","steps":["trace[675358329] 'agreement among raft nodes before linearized reading' (duration: 144.663171ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:56:57.147687Z","caller":"traceutil/trace.go:171","msg":"trace[1837281420] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"132.516117ms","start":"2026-02-25T09:56:57.01514Z","end":"2026-02-25T09:56:57.147656Z","steps":["trace[1837281420] 'process raft request' (duration: 132.456197ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:56:57.147727Z","caller":"traceutil/trace.go:171","msg":"trace[85439534] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"135.114655ms","start":"2026-02-25T09:56:57.012553Z","end":"2026-02-25T09:56:57.147667Z","steps":["trace[85439534] 'process raft request' (duration: 68.096031ms)","trace[85439534] 'compare' (duration: 66.752463ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:56:57.301772Z","caller":"traceutil/trace.go:171","msg":"trace[246779688] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"132.477637ms","start":"2026-02-25T09:56:57.169261Z","end":"2026-02-25T09:56:57.301739Z","steps":["trace[246779688] 'read index received' (duration: 81.562505ms)","trace[246779688] 'applied index is now lower than readState.Index' (duration: 50.913942ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:56:57.302483Z","caller":"traceutil/trace.go:171","msg":"trace[1253313840] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"133.664396ms","start":"2026-02-25T09:56:57.168754Z","end":"2026-02-25T09:56:57.302418Z","steps":["trace[1253313840] 'process raft request' (duration: 82.163868ms)","trace[1253313840] 'compare' (duration: 50.648321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:56:57.302563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.293954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T09:56:57.302864Z","caller":"traceutil/trace.go:171","msg":"trace[22914940] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:281; }","duration":"133.668357ms","start":"2026-02-25T09:56:57.169183Z","end":"2026-02-25T09:56:57.302852Z","steps":["trace[22914940] 'agreement among raft nodes before linearized reading' (duration: 133.237754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:56:57.658244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.432491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:56:57.658501Z","caller":"traceutil/trace.go:171","msg":"trace[1656613540] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"191.598228ms","start":"2026-02-25T09:56:57.466889Z","end":"2026-02-25T09:56:57.658487Z","steps":["trace[1656613540] 'process raft request' (duration: 72.858405ms)","trace[1656613540] 'compare' (duration: 118.180019ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:56:57.658874Z","caller":"traceutil/trace.go:171","msg":"trace[1807225500] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"191.633118ms","start":"2026-02-25T09:56:57.467212Z","end":"2026-02-25T09:56:57.658845Z","steps":["trace[1807225500] 'process raft request' (duration: 191.153935ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:56:57.83006Z","caller":"traceutil/trace.go:171","msg":"trace[1538083070] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"118.684842ms","start":"2026-02-25T09:56:57.711355Z","end":"2026-02-25T09:56:57.83004Z","steps":["trace[1538083070] 'read index received' (duration: 118.572411ms)","trace[1538083070] 'applied index is now lower than readState.Index' (duration: 111.351µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:56:57.830175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.387111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:56:57.830201Z","caller":"traceutil/trace.go:171","msg":"trace[488060626] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:288; }","duration":"163.452112ms","start":"2026-02-25T09:56:57.666741Z","end":"2026-02-25T09:56:57.830193Z","steps":["trace[488060626] 'agreement among raft nodes before linearized reading' (duration: 163.375361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:56:57.830483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.563515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:56:57.830579Z","caller":"traceutil/trace.go:171","msg":"trace[945057556] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:288; }","duration":"146.713646ms","start":"2026-02-25T09:56:57.683843Z","end":"2026-02-25T09:56:57.830556Z","steps":["trace[945057556] 'agreement among raft nodes before linearized reading' (duration: 146.499135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:56:57.830464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.443094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-25T09:56:57.830739Z","caller":"traceutil/trace.go:171","msg":"trace[1868928707] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:288; }","duration":"146.738486ms","start":"2026-02-25T09:56:57.683969Z","end":"2026-02-25T09:56:57.830708Z","steps":["trace[1868928707] 'agreement among raft nodes before linearized reading' (duration: 146.301643ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:56:57.830994Z","caller":"traceutil/trace.go:171","msg":"trace[143202287] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"146.827636ms","start":"2026-02-25T09:56:57.684155Z","end":"2026-02-25T09:56:57.830983Z","steps":["trace[143202287] 'process raft request' (duration: 145.780879ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:56:57.890737Z","caller":"traceutil/trace.go:171","msg":"trace[1967991792] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"175.674837ms","start":"2026-02-25T09:56:57.714961Z","end":"2026-02-25T09:56:57.890636Z","steps":["trace[1967991792] 'process raft request' (duration: 175.373755ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:56:57.890746Z","caller":"traceutil/trace.go:171","msg":"trace[1586624797] transaction","detail":"{read_only:false; number_of_response:0; response_revision:289; }","duration":"102.928144ms","start":"2026-02-25T09:56:57.787773Z","end":"2026-02-25T09:56:57.890701Z","steps":["trace[1586624797] 'process raft request' (duration: 102.743482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:01.843715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.120009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:57:01.843845Z","caller":"traceutil/trace.go:171","msg":"trace[447538935] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"176.162005ms","start":"2026-02-25T09:57:01.667653Z","end":"2026-02-25T09:57:01.843815Z","steps":["trace[447538935] 'read index received' (duration: 52.15µs)","trace[447538935] 'applied index is now lower than readState.Index' (duration: 176.108705ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:01.843918Z","caller":"traceutil/trace.go:171","msg":"trace[1127318920] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"218.810411ms","start":"2026-02-25T09:57:01.625055Z","end":"2026-02-25T09:57:01.843866Z","steps":["trace[1127318920] 'process raft request' (duration: 28.30199ms)","trace[1127318920] 'compare' (duration: 189.928857ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:01.844023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.924489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T09:57:01.844065Z","caller":"traceutil/trace.go:171","msg":"trace[334743884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:370; }","duration":"170.99946ms","start":"2026-02-25T09:57:01.673055Z","end":"2026-02-25T09:57:01.844055Z","steps":["trace[334743884] 'agreement among raft nodes before linearized reading' (duration: 170.93361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:01.843962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.305326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T09:57:01.84432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.823158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T09:57:01.844343Z","caller":"traceutil/trace.go:171","msg":"trace[240300855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"118.892849ms","start":"2026-02-25T09:57:01.725443Z","end":"2026-02-25T09:57:01.844336Z","steps":["trace[240300855] 'agreement among raft nodes before linearized reading' (duration: 118.856949ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:01.844346Z","caller":"traceutil/trace.go:171","msg":"trace[629027571] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"176.721028ms","start":"2026-02-25T09:57:01.667603Z","end":"2026-02-25T09:57:01.844324Z","steps":["trace[629027571] 'agreement among raft nodes before linearized reading' (duration: 176.311335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:04.355362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876099181,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T09:57:04.856074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876099181,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T09:57:05.356404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876099181,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T09:57:05.772394Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.024170389s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T09:57:05.772681Z","caller":"traceutil/trace.go:171","msg":"trace[1849246729] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"3.024617462s","start":"2026-02-25T09:57:02.748046Z","end":"2026-02-25T09:57:05.772663Z","steps":["trace[1849246729] 'process raft request' (duration: 3.024500331s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:05.772778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:02.748011Z","time spent":"3.024697862s","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4396,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:05.848706Z","caller":"traceutil/trace.go:171","msg":"trace[2097085332] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"1.993497434s","start":"2026-02-25T09:57:03.855183Z","end":"2026-02-25T09:57:05.848681Z","steps":["trace[2097085332] 'read index received' (duration: 1.917603976s)","trace[2097085332] 'applied index is now lower than readState.Index' (duration: 75.892538ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:05.848802Z","caller":"traceutil/trace.go:171","msg":"trace[1008479525] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"2.145938365s","start":"2026-02-25T09:57:03.70283Z","end":"2026-02-25T09:57:05.848769Z","steps":["trace[1008479525] 'process raft request' (duration: 2.144600727s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:05.848859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.993668295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T09:57:05.848896Z","caller":"traceutil/trace.go:171","msg":"trace[1265040377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"1.993764157s","start":"2026-02-25T09:57:03.85512Z","end":"2026-02-25T09:57:05.848884Z","steps":["trace[1265040377] 'agreement among raft nodes before linearized reading' (duration: 1.993677546s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:05.848931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:03.855089Z","time spent":"1.993834377s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T09:57:05.848967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:03.702811Z","time spent":"2.146068098s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:05.849819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.990852688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T09:57:05.8499Z","caller":"traceutil/trace.go:171","msg":"trace[1927027109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:392; }","duration":"1.990982298s","start":"2026-02-25T09:57:03.858899Z","end":"2026-02-25T09:57:05.849881Z","steps":["trace[1927027109] 'agreement among raft nodes before linearized reading' (duration: 1.990839597s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:05.849939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:03.858869Z","time spent":"1.991059489s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T09:57:05.850135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.642674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T09:57:05.850174Z","caller":"traceutil/trace.go:171","msg":"trace[1872182802] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:392; }","duration":"510.722934ms","start":"2026-02-25T09:57:05.339441Z","end":"2026-02-25T09:57:05.850164Z","steps":["trace[1872182802] 'agreement among raft nodes before linearized reading' (duration: 510.648234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:05.850231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:05.339411Z","time spent":"510.781985ms","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.12\" "} {"level":"warn","ts":"2026-02-25T09:57:05.850396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494469076s","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-25T09:57:05.850432Z","caller":"traceutil/trace.go:171","msg":"trace[1122044119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:392; }","duration":"1.494544277s","start":"2026-02-25T09:57:04.35588Z","end":"2026-02-25T09:57:05.850424Z","steps":["trace[1122044119] 'agreement among raft nodes before linearized reading' (duration: 1.494481446s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:05.850457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:04.355856Z","time spent":"1.494595457s","remote":"127.0.0.1:49570","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-25T09:57:05.8506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851773525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T09:57:05.849819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.191465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:05.850633Z","caller":"traceutil/trace.go:171","msg":"trace[119135277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:392; }","duration":"1.851871365s","start":"2026-02-25T09:57:03.998752Z","end":"2026-02-25T09:57:05.850624Z","steps":["trace[119135277] 'agreement among raft nodes before linearized reading' (duration: 1.851811865s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:05.850659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:03.998709Z","time spent":"1.851943705s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T09:57:05.8507Z","caller":"traceutil/trace.go:171","msg":"trace[34373966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"449.074831ms","start":"2026-02-25T09:57:05.401572Z","end":"2026-02-25T09:57:05.850647Z","steps":["trace[34373966] 'agreement among raft nodes before linearized reading' (duration: 448.205236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:05.850835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:05.401538Z","time spent":"449.281822ms","remote":"127.0.0.1:49292","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-25T09:57:10.663025Z","caller":"traceutil/trace.go:171","msg":"trace[771243712] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"234.826723ms","start":"2026-02-25T09:57:10.428176Z","end":"2026-02-25T09:57:10.663003Z","steps":["trace[771243712] 'process raft request' (duration: 234.716342ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:16.593435Z","caller":"traceutil/trace.go:171","msg":"trace[1832605151] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"632.773891ms","start":"2026-02-25T09:57:15.960638Z","end":"2026-02-25T09:57:16.593412Z","steps":["trace[1832605151] 'process raft request' (duration: 632.64126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:16.593576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:15.960619Z","time spent":"632.888553ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:16.681566Z","caller":"traceutil/trace.go:171","msg":"trace[1126864261] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"586.793536ms","start":"2026-02-25T09:57:16.094748Z","end":"2026-02-25T09:57:16.681542Z","steps":["trace[1126864261] 'process raft request' (duration: 586.740565ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:16.681594Z","caller":"traceutil/trace.go:171","msg":"trace[765162086] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"720.791314ms","start":"2026-02-25T09:57:15.960767Z","end":"2026-02-25T09:57:16.681558Z","steps":["trace[765162086] 'process raft request' (duration: 720.555112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:16.681705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:16.094722Z","time spent":"586.910647ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:16.681854Z","caller":"traceutil/trace.go:171","msg":"trace[909762051] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"721.037435ms","start":"2026-02-25T09:57:15.960809Z","end":"2026-02-25T09:57:16.681846Z","steps":["trace[909762051] 'process raft request' (duration: 720.624672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:16.6819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:15.960805Z","time spent":"721.072656ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:16.681893Z","caller":"traceutil/trace.go:171","msg":"trace[1404721882] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"720.824975ms","start":"2026-02-25T09:57:15.961048Z","end":"2026-02-25T09:57:16.681873Z","steps":["trace[1404721882] 'process raft request' (duration: 720.415001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:16.682026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:15.961043Z","time spent":"720.906085ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:16.682483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:15.960757Z","time spent":"720.917845ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:27.230489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876099429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T09:57:27.731454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876099429,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T09:57:28.096253Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.443797855s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T09:57:28.096711Z","caller":"traceutil/trace.go:171","msg":"trace[2139199941] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.444313397s","start":"2026-02-25T09:57:26.652371Z","end":"2026-02-25T09:57:28.096684Z","steps":["trace[2139199941] 'process raft request' (duration: 1.444120658s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:28.096878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:26.652353Z","time spent":"1.444442652s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:28.658829Z","caller":"traceutil/trace.go:171","msg":"trace[1245064608] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:531; }","duration":"1.928612982s","start":"2026-02-25T09:57:26.730192Z","end":"2026-02-25T09:57:28.658805Z","steps":["trace[1245064608] 'read index received' (duration: 1.366263614s)","trace[1245064608] 'applied index is now lower than readState.Index' (duration: 562.348088ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:28.659051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.928845163s","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-25T09:57:28.659138Z","caller":"traceutil/trace.go:171","msg":"trace[159865843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"1.928939017s","start":"2026-02-25T09:57:26.730178Z","end":"2026-02-25T09:57:28.659117Z","steps":["trace[159865843] 'agreement among raft nodes before linearized reading' (duration: 1.928747998s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:28.659186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:26.730124Z","time spent":"1.929052531s","remote":"127.0.0.1:49570","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-25T09:57:28.660052Z","caller":"traceutil/trace.go:171","msg":"trace[1935824628] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.628018205s","start":"2026-02-25T09:57:27.03197Z","end":"2026-02-25T09:57:28.659988Z","steps":["trace[1935824628] 'process raft request' (duration: 1.626669121s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:28.66028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:27.031947Z","time spent":"1.628235936s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:28.665547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.563981806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:28.665587Z","caller":"traceutil/trace.go:171","msg":"trace[459760854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"1.564025308s","start":"2026-02-25T09:57:27.101553Z","end":"2026-02-25T09:57:28.665578Z","steps":["trace[459760854] 'agreement among raft nodes before linearized reading' (duration: 1.563949154s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:28.665609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:27.10151Z","time spent":"1.564094961s","remote":"127.0.0.1:49292","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-25T09:57:28.665661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912874906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T09:57:28.665718Z","caller":"traceutil/trace.go:171","msg":"trace[93963480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"1.912967579s","start":"2026-02-25T09:57:26.752735Z","end":"2026-02-25T09:57:28.665703Z","steps":["trace[93963480] 'agreement among raft nodes before linearized reading' (duration: 1.912856634s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:28.66575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:26.752715Z","time spent":"1.913027292s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T09:57:28.665821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.921805749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T09:57:28.665845Z","caller":"traceutil/trace.go:171","msg":"trace[1048765596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"1.921870671s","start":"2026-02-25T09:57:26.743967Z","end":"2026-02-25T09:57:28.665838Z","steps":["trace[1048765596] 'agreement among raft nodes before linearized reading' (duration: 1.921499363s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:28.665883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:26.743947Z","time spent":"1.921910902s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T09:57:29.709105Z","caller":"traceutil/trace.go:171","msg":"trace[753905418] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"162.162271ms","start":"2026-02-25T09:57:29.546916Z","end":"2026-02-25T09:57:29.709078Z","steps":["trace[753905418] 'process raft request' (duration: 162.012834ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:29.839275Z","caller":"traceutil/trace.go:171","msg":"trace[624890650] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"126.666782ms","start":"2026-02-25T09:57:29.71259Z","end":"2026-02-25T09:57:29.839257Z","steps":["trace[624890650] 'process raft request' (duration: 125.693747ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:29.845908Z","caller":"traceutil/trace.go:171","msg":"trace[1174318423] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"129.429023ms","start":"2026-02-25T09:57:29.716463Z","end":"2026-02-25T09:57:29.845892Z","steps":["trace[1174318423] 'process raft request' (duration: 129.400111ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:29.846147Z","caller":"traceutil/trace.go:171","msg":"trace[2066553882] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"133.065414ms","start":"2026-02-25T09:57:29.713075Z","end":"2026-02-25T09:57:29.84614Z","steps":["trace[2066553882] 'process raft request' (duration: 132.692347ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:30.108284Z","caller":"traceutil/trace.go:171","msg":"trace[402259043] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"135.292319ms","start":"2026-02-25T09:57:29.972452Z","end":"2026-02-25T09:57:30.107744Z","steps":["trace[402259043] 'process raft request' (duration: 94.001042ms)","trace[402259043] 'compare' (duration: 41.200153ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:30.270472Z","caller":"traceutil/trace.go:171","msg":"trace[166175861] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"252.642557ms","start":"2026-02-25T09:57:30.017804Z","end":"2026-02-25T09:57:30.270447Z","steps":["trace[166175861] 'process raft request' (duration: 190.590217ms)","trace[166175861] 'compare' (duration: 61.876752ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:30.270694Z","caller":"traceutil/trace.go:171","msg":"trace[596874833] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:548; }","duration":"248.350097ms","start":"2026-02-25T09:57:30.022312Z","end":"2026-02-25T09:57:30.270662Z","steps":["trace[596874833] 'read index received' (duration: 44.087856ms)","trace[596874833] 'applied index is now lower than readState.Index' (duration: 204.261441ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:30.270764Z","caller":"traceutil/trace.go:171","msg":"trace[2022877330] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"252.879718ms","start":"2026-02-25T09:57:30.017877Z","end":"2026-02-25T09:57:30.270757Z","steps":["trace[2022877330] 'process raft request' (duration: 252.540923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:30.270894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.192563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:30.27094Z","caller":"traceutil/trace.go:171","msg":"trace[425917261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"169.277747ms","start":"2026-02-25T09:57:30.101647Z","end":"2026-02-25T09:57:30.270924Z","steps":["trace[425917261] 'agreement among raft nodes before linearized reading' (duration: 169.142591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:30.271093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.777908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:30.271124Z","caller":"traceutil/trace.go:171","msg":"trace[465606499] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:530; }","duration":"248.836231ms","start":"2026-02-25T09:57:30.022277Z","end":"2026-02-25T09:57:30.271113Z","steps":["trace[465606499] 'agreement among raft nodes before linearized reading' (duration: 248.792569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:30.271167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.999239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T09:57:30.271203Z","caller":"traceutil/trace.go:171","msg":"trace[1847653968] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"165.066942ms","start":"2026-02-25T09:57:30.106125Z","end":"2026-02-25T09:57:30.271192Z","steps":["trace[1847653968] 'agreement among raft nodes before linearized reading' (duration: 164.954547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:32.414647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.989013376s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:57:32.415106Z","caller":"traceutil/trace.go:171","msg":"trace[56878532] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"2.141243641s","start":"2026-02-25T09:57:30.273832Z","end":"2026-02-25T09:57:32.415076Z","steps":["trace[56878532] 'process raft request' (duration: 151.741484ms)","trace[56878532] 'compare' (duration: 1.988561715s)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:32.415159Z","caller":"traceutil/trace.go:171","msg":"trace[539983183] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"2.140537309s","start":"2026-02-25T09:57:30.274579Z","end":"2026-02-25T09:57:32.415116Z","steps":["trace[539983183] 'read index received' (duration: 151.01058ms)","trace[539983183] 'applied index is now lower than readState.Index' (duration: 1.989519109s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:32.415224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:30.273806Z","time spent":"2.141376507s","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2550,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T09:57:32.415264Z","caller":"traceutil/trace.go:171","msg":"trace[964086122] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"2.140010614s","start":"2026-02-25T09:57:30.27523Z","end":"2026-02-25T09:57:32.415241Z","steps":["trace[964086122] 'process raft request' (duration: 2.139511931s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:32.415388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:30.275217Z","time spent":"2.140107059s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:32.627606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.780716ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T09:57:32.62772Z","caller":"traceutil/trace.go:171","msg":"trace[545227896] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"212.457211ms","start":"2026-02-25T09:57:32.415242Z","end":"2026-02-25T09:57:32.627699Z","steps":["trace[545227896] 'read index received' (duration: 76.413µs)","trace[545227896] 'applied index is now lower than readState.Index' (duration: 212.379058ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:32.627893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.295878179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-25T09:57:32.627922Z","caller":"traceutil/trace.go:171","msg":"trace[81942845] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:532; }","duration":"2.295915211s","start":"2026-02-25T09:57:30.331996Z","end":"2026-02-25T09:57:32.627912Z","steps":["trace[81942845] 'agreement among raft nodes before linearized reading' (duration: 2.295753184s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:32.627958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:30.331974Z","time spent":"2.295973914s","remote":"127.0.0.1:49370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12750,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-25T09:57:32.628342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.353758465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3343"} {"level":"info","ts":"2026-02-25T09:57:32.628376Z","caller":"traceutil/trace.go:171","msg":"trace[1708145524] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:532; }","duration":"2.353813917s","start":"2026-02-25T09:57:30.27455Z","end":"2026-02-25T09:57:32.628364Z","steps":["trace[1708145524] 'agreement among raft nodes before linearized reading' (duration: 2.140742718s)","trace[1708145524] 'range keys from in-memory index tree' (duration: 212.979545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:32.628377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503830322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T09:57:32.628404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:30.274539Z","time spent":"2.353858419s","remote":"127.0.0.1:49756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":3366,"request content":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" "} {"level":"info","ts":"2026-02-25T09:57:32.628426Z","caller":"traceutil/trace.go:171","msg":"trace[2054601011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"1.503932695s","start":"2026-02-25T09:57:31.12448Z","end":"2026-02-25T09:57:32.628413Z","steps":["trace[2054601011] 'agreement among raft nodes before linearized reading' (duration: 1.503836881s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:32.628654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945252292s","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-25T09:57:32.628761Z","caller":"traceutil/trace.go:171","msg":"trace[1919629235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"1.94542711s","start":"2026-02-25T09:57:30.683303Z","end":"2026-02-25T09:57:32.62873Z","steps":["trace[1919629235] 'agreement among raft nodes before linearized reading' (duration: 1.945077854s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:32.628815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:30.683289Z","time spent":"1.945512824s","remote":"127.0.0.1:49570","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-25T09:57:32.628813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943122173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T09:57:32.628849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.943318813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T09:57:32.628883Z","caller":"traceutil/trace.go:171","msg":"trace[345778081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.943227158s","start":"2026-02-25T09:57:30.685638Z","end":"2026-02-25T09:57:32.628866Z","steps":["trace[345778081] 'agreement among raft nodes before linearized reading' (duration: 1.942940985s)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:32.62891Z","caller":"traceutil/trace.go:171","msg":"trace[112860827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.943394276s","start":"2026-02-25T09:57:30.685503Z","end":"2026-02-25T09:57:32.628898Z","steps":["trace[112860827] 'agreement among raft nodes before linearized reading' (duration: 1.94325846s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:32.628898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:31.124452Z","time spent":"1.503998829s","remote":"127.0.0.1:49292","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-25T09:57:32.628954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:30.685496Z","time spent":"1.943448108s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T09:57:32.628972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:30.68562Z","time spent":"1.943322072s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T09:57:32.648677Z","caller":"traceutil/trace.go:171","msg":"trace[439329028] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"229.063313ms","start":"2026-02-25T09:57:32.419595Z","end":"2026-02-25T09:57:32.648658Z","steps":["trace[439329028] 'process raft request' (duration: 228.908346ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:32.667991Z","caller":"traceutil/trace.go:171","msg":"trace[912219849] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"247.521499ms","start":"2026-02-25T09:57:32.420453Z","end":"2026-02-25T09:57:32.667974Z","steps":["trace[912219849] 'process raft request' (duration: 247.30759ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:32.668057Z","caller":"traceutil/trace.go:171","msg":"trace[1303941865] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"245.709648ms","start":"2026-02-25T09:57:32.422329Z","end":"2026-02-25T09:57:32.668039Z","steps":["trace[1303941865] 'process raft request' (duration: 245.565322ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:32.669636Z","caller":"traceutil/trace.go:171","msg":"trace[277581692] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"246.795576ms","start":"2026-02-25T09:57:32.421218Z","end":"2026-02-25T09:57:32.668014Z","steps":["trace[277581692] 'process raft request' (duration: 246.626259ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:33.021971Z","caller":"traceutil/trace.go:171","msg":"trace[1132027897] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"337.16049ms","start":"2026-02-25T09:57:32.684795Z","end":"2026-02-25T09:57:33.021955Z","steps":["trace[1132027897] 'process raft request' (duration: 337.003013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:33.022109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:32.684765Z","time spent":"337.256835ms","remote":"127.0.0.1:49782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3210,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:33.288727Z","caller":"traceutil/trace.go:171","msg":"trace[585529404] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"258.899743ms","start":"2026-02-25T09:57:33.029805Z","end":"2026-02-25T09:57:33.288705Z","steps":["trace[585529404] 'process raft request' (duration: 258.766317ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:36.689632Z","caller":"traceutil/trace.go:171","msg":"trace[722211686] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"257.023227ms","start":"2026-02-25T09:57:36.43259Z","end":"2026-02-25T09:57:36.689613Z","steps":["trace[722211686] 'process raft request' (duration: 256.902002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:36.926509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.959138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:57:36.926686Z","caller":"traceutil/trace.go:171","msg":"trace[655775575] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"228.246715ms","start":"2026-02-25T09:57:36.698401Z","end":"2026-02-25T09:57:36.926648Z","steps":["trace[655775575] 'read index received' (duration: 107.010566ms)","trace[655775575] 'applied index is now lower than readState.Index' (duration: 121.234589ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:36.926714Z","caller":"traceutil/trace.go:171","msg":"trace[731772725] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"229.97314ms","start":"2026-02-25T09:57:36.696703Z","end":"2026-02-25T09:57:36.926676Z","steps":["trace[731772725] 'process raft request' (duration: 108.72724ms)","trace[731772725] 'compare' (duration: 120.786351ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:36.926837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.430783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T09:57:36.926899Z","caller":"traceutil/trace.go:171","msg":"trace[835196106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"228.517926ms","start":"2026-02-25T09:57:36.698365Z","end":"2026-02-25T09:57:36.926883Z","steps":["trace[835196106] 'agreement among raft nodes before linearized reading' (duration: 228.37065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:36.926942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.391048ms","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-25T09:57:36.926988Z","caller":"traceutil/trace.go:171","msg":"trace[1793323472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"227.463211ms","start":"2026-02-25T09:57:36.699513Z","end":"2026-02-25T09:57:36.926976Z","steps":["trace[1793323472] 'agreement among raft nodes before linearized reading' (duration: 227.283203ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:39.001271Z","caller":"traceutil/trace.go:171","msg":"trace[1916352681] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"303.96661ms","start":"2026-02-25T09:57:38.697228Z","end":"2026-02-25T09:57:39.001195Z","steps":["trace[1916352681] 'process raft request' (duration: 303.714309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:39.001588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:38.697212Z","time spent":"304.180799ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:39.002218Z","caller":"traceutil/trace.go:171","msg":"trace[765267484] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"122.961528ms","start":"2026-02-25T09:57:38.879233Z","end":"2026-02-25T09:57:39.002195Z","steps":["trace[765267484] 'process raft request' (duration: 122.874474ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:39.002234Z","caller":"traceutil/trace.go:171","msg":"trace[593871071] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"165.440076ms","start":"2026-02-25T09:57:38.836747Z","end":"2026-02-25T09:57:39.002187Z","steps":["trace[593871071] 'process raft request' (duration: 165.235848ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:40.352908Z","caller":"traceutil/trace.go:171","msg":"trace[2031764795] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"328.800918ms","start":"2026-02-25T09:57:40.023765Z","end":"2026-02-25T09:57:40.352565Z","steps":["trace[2031764795] 'process raft request' (duration: 328.674763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:40.353022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:40.023744Z","time spent":"329.220575ms","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:41.508699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876099534,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T09:57:42.009915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876099534,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T09:57:42.510061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876099534,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T09:57:43.011061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876099534,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T09:57:43.107418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000367285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T09:57:43.107482Z","caller":"traceutil/trace.go:171","msg":"trace[1598665219] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000437137s","start":"2026-02-25T09:57:41.107035Z","end":"2026-02-25T09:57:43.107472Z","steps":["trace[1598665219] 'agreement among raft nodes before linearized reading' (duration: 2.000366954s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:43.107505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:41.106976Z","time spent":"2.00052377s","remote":"127.0.0.1:49292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 09:57:43 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T09:57:43.291819Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.885326104s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T09:57:44.764769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472739725s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T09:57:44.76485Z","caller":"traceutil/trace.go:171","msg":"trace[333122711] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"3.756453916s","start":"2026-02-25T09:57:41.008382Z","end":"2026-02-25T09:57:44.764836Z","steps":["trace[333122711] 'read index received' (duration: 2.283529613s)","trace[333122711] 'applied index is now lower than readState.Index' (duration: 1.472922543s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:44.765129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.756746588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T09:57:44.765161Z","caller":"traceutil/trace.go:171","msg":"trace[417173773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"3.75681628s","start":"2026-02-25T09:57:41.008336Z","end":"2026-02-25T09:57:44.765152Z","steps":["trace[417173773] 'agreement among raft nodes before linearized reading' (duration: 3.756622983s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:44.765185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:41.008318Z","time spent":"3.756861812s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T09:57:44.765213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.751033305s","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-25T09:57:44.765284Z","caller":"traceutil/trace.go:171","msg":"trace[2047035338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"3.75114403s","start":"2026-02-25T09:57:41.014128Z","end":"2026-02-25T09:57:44.765272Z","steps":["trace[2047035338] 'agreement among raft nodes before linearized reading' (duration: 3.750966743s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:44.76531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:41.014113Z","time spent":"3.751192732s","remote":"127.0.0.1:49570","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-25T09:57:44.765486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.750541895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T09:57:44.765513Z","caller":"traceutil/trace.go:171","msg":"trace[977700024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"3.750597117s","start":"2026-02-25T09:57:41.01491Z","end":"2026-02-25T09:57:44.765507Z","steps":["trace[977700024] 'agreement among raft nodes before linearized reading' (duration: 3.750539595s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:44.765539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:41.014891Z","time spent":"3.750640989s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T09:57:44.765605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.744104444s","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-25T09:57:44.767174Z","caller":"traceutil/trace.go:171","msg":"trace[1993853100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"3.745719967s","start":"2026-02-25T09:57:41.021442Z","end":"2026-02-25T09:57:44.767162Z","steps":["trace[1993853100] 'agreement among raft nodes before linearized reading' (duration: 3.743994879s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:44.767254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:41.021428Z","time spent":"3.745782389s","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T09:57:44.765806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.133708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T09:57:44.765832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658455684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:44.77151Z","caller":"traceutil/trace.go:171","msg":"trace[518367988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"1.664143507s","start":"2026-02-25T09:57:43.107349Z","end":"2026-02-25T09:57:44.771492Z","steps":["trace[518367988] 'agreement among raft nodes before linearized reading' (duration: 1.658474495s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:44.771595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:43.107336Z","time spent":"1.66422232s","remote":"127.0.0.1:49286","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-25T09:57:44.771378Z","caller":"traceutil/trace.go:171","msg":"trace[1695565921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"727.729467ms","start":"2026-02-25T09:57:44.043631Z","end":"2026-02-25T09:57:44.771361Z","steps":["trace[1695565921] 'agreement among raft nodes before linearized reading' (duration: 722.157639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:44.771713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:44.043614Z","time spent":"728.05908ms","remote":"127.0.0.1:49292","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-25T09:57:45.484696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.442536ms","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-25T09:57:45.484784Z","caller":"traceutil/trace.go:171","msg":"trace[557756585] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:567; }","duration":"243.540289ms","start":"2026-02-25T09:57:45.241232Z","end":"2026-02-25T09:57:45.484773Z","steps":["trace[557756585] 'range keys from in-memory index tree' (duration: 243.430205ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:45.486668Z","caller":"traceutil/trace.go:171","msg":"trace[1027268763] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"128.461059ms","start":"2026-02-25T09:57:45.358188Z","end":"2026-02-25T09:57:45.486649Z","steps":["trace[1027268763] 'read index received' (duration: 128.320274ms)","trace[1027268763] 'applied index is now lower than readState.Index' (duration: 139.915µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:45.486822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.617935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.12\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T09:57:45.486849Z","caller":"traceutil/trace.go:171","msg":"trace[1266010514] range","detail":"{range_begin:/registry/masterleases/162.253.55.12; range_end:; response_count:1; response_revision:568; }","duration":"128.701569ms","start":"2026-02-25T09:57:45.35814Z","end":"2026-02-25T09:57:45.486842Z","steps":["trace[1266010514] 'agreement among raft nodes before linearized reading' (duration: 128.582044ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:45.48672Z","caller":"traceutil/trace.go:171","msg":"trace[1193472287] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"355.686076ms","start":"2026-02-25T09:57:45.131023Z","end":"2026-02-25T09:57:45.486709Z","steps":["trace[1193472287] 'process raft request' (duration: 355.508399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:45.486926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:45.130998Z","time spent":"355.874773ms","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:45.888366Z","caller":"traceutil/trace.go:171","msg":"trace[1744273856] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"126.94358ms","start":"2026-02-25T09:57:45.761404Z","end":"2026-02-25T09:57:45.888347Z","steps":["trace[1744273856] 'process raft request' (duration: 126.720002ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:47.150898Z","caller":"traceutil/trace.go:171","msg":"trace[1902568657] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"327.096487ms","start":"2026-02-25T09:57:46.823784Z","end":"2026-02-25T09:57:47.150881Z","steps":["trace[1902568657] 'read index received' (duration: 326.894689ms)","trace[1902568657] 'applied index is now lower than readState.Index' (duration: 201.308µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:47.150976Z","caller":"traceutil/trace.go:171","msg":"trace[1035840391] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"359.918384ms","start":"2026-02-25T09:57:46.791032Z","end":"2026-02-25T09:57:47.15095Z","steps":["trace[1035840391] 'process raft request' (duration: 359.692395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:47.151073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.249052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T09:57:47.151102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:46.79101Z","time spent":"360.023968ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:47.151102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.299084ms","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-25T09:57:47.151169Z","caller":"traceutil/trace.go:171","msg":"trace[916716454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"327.417709ms","start":"2026-02-25T09:57:46.823738Z","end":"2026-02-25T09:57:47.151155Z","steps":["trace[916716454] 'agreement among raft nodes before linearized reading' (duration: 327.225392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:47.151196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:46.823716Z","time spent":"327.474201ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T09:57:47.151123Z","caller":"traceutil/trace.go:171","msg":"trace[973270566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"327.361087ms","start":"2026-02-25T09:57:46.823751Z","end":"2026-02-25T09:57:47.151112Z","steps":["trace[973270566] 'agreement among raft nodes before linearized reading' (duration: 327.227672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:47.151283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:46.82373Z","time spent":"327.546554ms","remote":"127.0.0.1:49570","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-25T09:57:47.151337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.128241ms","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-25T09:57:47.151436Z","caller":"traceutil/trace.go:171","msg":"trace[1420470914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"325.261706ms","start":"2026-02-25T09:57:46.826154Z","end":"2026-02-25T09:57:47.151416Z","steps":["trace[1420470914] 'agreement among raft nodes before linearized reading' (duration: 325.008516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:47.15148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:46.826143Z","time spent":"325.326688ms","remote":"127.0.0.1:49570","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-25T09:57:49.472347Z","caller":"traceutil/trace.go:171","msg":"trace[741173049] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"264.636833ms","start":"2026-02-25T09:57:49.207688Z","end":"2026-02-25T09:57:49.472324Z","steps":["trace[741173049] 'process raft request' (duration: 264.519709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:49.472586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.638314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T09:57:49.472679Z","caller":"traceutil/trace.go:171","msg":"trace[1841384484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"317.779519ms","start":"2026-02-25T09:57:49.154882Z","end":"2026-02-25T09:57:49.472662Z","steps":["trace[1841384484] 'agreement among raft nodes before linearized reading' (duration: 317.52928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:49.472737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:49.154862Z","time spent":"317.862603ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T09:57:49.472928Z","caller":"traceutil/trace.go:171","msg":"trace[1697443078] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"317.331062ms","start":"2026-02-25T09:57:49.154926Z","end":"2026-02-25T09:57:49.472257Z","steps":["trace[1697443078] 'read index received' (duration: 58.059448ms)","trace[1697443078] 'applied index is now lower than readState.Index' (duration: 259.270724ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:49.473053Z","caller":"traceutil/trace.go:171","msg":"trace[1819034928] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"345.707993ms","start":"2026-02-25T09:57:49.126648Z","end":"2026-02-25T09:57:49.472356Z","steps":["trace[1819034928] 'process raft request' (duration: 344.216377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:49.47321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:49.126623Z","time spent":"346.518943ms","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17396,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:49.474488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.69096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T09:57:49.474522Z","caller":"traceutil/trace.go:171","msg":"trace[664906906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"302.758953ms","start":"2026-02-25T09:57:49.171753Z","end":"2026-02-25T09:57:49.474512Z","steps":["trace[664906906] 'agreement among raft nodes before linearized reading' (duration: 302.66574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:49.474544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:49.171742Z","time spent":"302.796794ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T09:57:49.474582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.352775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T09:57:49.474652Z","caller":"traceutil/trace.go:171","msg":"trace[66065083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"303.47932ms","start":"2026-02-25T09:57:49.171159Z","end":"2026-02-25T09:57:49.474639Z","steps":["trace[66065083] 'agreement among raft nodes before linearized reading' (duration: 303.293593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:49.474678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:49.171125Z","time spent":"303.547772ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T09:57:49.479664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.337964ms","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-25T09:57:49.479746Z","caller":"traceutil/trace.go:171","msg":"trace[1541237036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:583; }","duration":"308.727513ms","start":"2026-02-25T09:57:49.171005Z","end":"2026-02-25T09:57:49.479732Z","steps":["trace[1541237036] 'agreement among raft nodes before linearized reading' (duration: 303.288152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:49.479794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:49.170989Z","time spent":"308.791376ms","remote":"127.0.0.1:49570","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-25T09:57:50.616795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.42042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:57:50.616863Z","caller":"traceutil/trace.go:171","msg":"trace[1311054316] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"513.539615ms","start":"2026-02-25T09:57:50.103312Z","end":"2026-02-25T09:57:50.616852Z","steps":["trace[1311054316] 'read index received' (duration: 142.926808ms)","trace[1311054316] 'applied index is now lower than readState.Index' (duration: 370.612167ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:50.616903Z","caller":"traceutil/trace.go:171","msg":"trace[132453553] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"883.312777ms","start":"2026-02-25T09:57:49.733585Z","end":"2026-02-25T09:57:50.616897Z","steps":["trace[132453553] 'process raft request' (duration: 512.733731ms)","trace[132453553] 'compare' (duration: 370.188322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:50.616926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:49.733567Z","time spent":"883.348499ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T09:57:50.617063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.748114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:50.617081Z","caller":"traceutil/trace.go:171","msg":"trace[859788413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"513.795726ms","start":"2026-02-25T09:57:50.10328Z","end":"2026-02-25T09:57:50.617076Z","steps":["trace[859788413] 'agreement among raft nodes before linearized reading' (duration: 513.745504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:50.617094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:50.103265Z","time spent":"513.826406ms","remote":"127.0.0.1:49292","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-25T09:57:51.466586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.512269ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T09:57:51.466842Z","caller":"traceutil/trace.go:171","msg":"trace[1723874370] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"849.942562ms","start":"2026-02-25T09:57:50.616876Z","end":"2026-02-25T09:57:51.466819Z","steps":["trace[1723874370] 'read index received' (duration: 384.121922ms)","trace[1723874370] 'applied index is now lower than readState.Index' (duration: 465.81696ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:51.466904Z","caller":"traceutil/trace.go:171","msg":"trace[1215790047] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"458.102968ms","start":"2026-02-25T09:57:51.008773Z","end":"2026-02-25T09:57:51.466876Z","steps":["trace[1215790047] 'process raft request' (duration: 457.909321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:51.467032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:51.008751Z","time spent":"458.218692ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T09:57:51.792961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174350683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:51.793032Z","caller":"traceutil/trace.go:171","msg":"trace[2091431328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"1.174472077s","start":"2026-02-25T09:57:50.618544Z","end":"2026-02-25T09:57:51.793016Z","steps":["trace[2091431328] 'agreement among raft nodes before linearized reading' (duration: 848.410475ms)","trace[2091431328] 'range keys from in-memory index tree' (duration: 325.949248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:51.793069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:50.618529Z","time spent":"1.17453364s","remote":"127.0.0.1:49286","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-25T09:57:51.793414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.512042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v46cx\" ","response":"range_response_count:1 size:17411"} {"level":"info","ts":"2026-02-25T09:57:51.793468Z","caller":"traceutil/trace.go:171","msg":"trace[1896427543] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v46cx; range_end:; response_count:1; response_revision:592; }","duration":"779.596405ms","start":"2026-02-25T09:57:51.013861Z","end":"2026-02-25T09:57:51.793457Z","steps":["trace[1896427543] 'agreement among raft nodes before linearized reading' (duration: 453.113297ms)","trace[1896427543] 'range keys from in-memory index tree' (duration: 326.081873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:51.793494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:51.013846Z","time spent":"779.642537ms","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17434,"request content":"key:\"/registry/pods/kube-system/cilium-v46cx\" "} {"level":"warn","ts":"2026-02-25T09:57:51.793746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416994369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T09:57:51.79378Z","caller":"traceutil/trace.go:171","msg":"trace[143227449] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:592; }","duration":"1.417063912s","start":"2026-02-25T09:57:50.376707Z","end":"2026-02-25T09:57:51.793771Z","steps":["trace[143227449] 'agreement among raft nodes before linearized reading' (duration: 1.090223952s)","trace[143227449] 'range keys from in-memory index tree' (duration: 326.778388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:51.793859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:50.376692Z","time spent":"1.417160595s","remote":"127.0.0.1:49384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T09:57:51.794156Z","caller":"traceutil/trace.go:171","msg":"trace[980840883] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"291.663808ms","start":"2026-02-25T09:57:51.50248Z","end":"2026-02-25T09:57:51.794144Z","steps":["trace[980840883] 'read index received' (duration: 210.098678ms)","trace[980840883] 'applied index is now lower than readState.Index' (duration: 81.56365ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:51.794187Z","caller":"traceutil/trace.go:171","msg":"trace[1301143775] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"321.492127ms","start":"2026-02-25T09:57:51.472672Z","end":"2026-02-25T09:57:51.794164Z","steps":["trace[1301143775] 'process raft request' (duration: 239.824684ms)","trace[1301143775] 'compare' (duration: 80.306034ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:51.794307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:51.472646Z","time spent":"321.601921ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T09:57:51.79455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.054524ms","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-25T09:57:51.794649Z","caller":"traceutil/trace.go:171","msg":"trace[1164437417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"292.197079ms","start":"2026-02-25T09:57:51.50243Z","end":"2026-02-25T09:57:51.794627Z","steps":["trace[1164437417] 'agreement among raft nodes before linearized reading' (duration: 291.881736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:51.794669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.577105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T09:57:51.794596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.041051ms","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-25T09:57:51.79471Z","caller":"traceutil/trace.go:171","msg":"trace[1803626549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"281.656027ms","start":"2026-02-25T09:57:51.513042Z","end":"2026-02-25T09:57:51.794698Z","steps":["trace[1803626549] 'agreement among raft nodes before linearized reading' (duration: 281.539853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:51.794731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.586394ms","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-25T09:57:51.79482Z","caller":"traceutil/trace.go:171","msg":"trace[1140872969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"281.699109ms","start":"2026-02-25T09:57:51.513104Z","end":"2026-02-25T09:57:51.794804Z","steps":["trace[1140872969] 'agreement among raft nodes before linearized reading' (duration: 281.470401ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:51.794717Z","caller":"traceutil/trace.go:171","msg":"trace[1326179925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"282.199806ms","start":"2026-02-25T09:57:51.512509Z","end":"2026-02-25T09:57:51.794708Z","steps":["trace[1326179925] 'agreement among raft nodes before linearized reading' (duration: 282.02191ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:51.96301Z","caller":"traceutil/trace.go:171","msg":"trace[557024931] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"161.534723ms","start":"2026-02-25T09:57:51.801453Z","end":"2026-02-25T09:57:51.962987Z","steps":["trace[557024931] 'read index received' (duration: 159.052013ms)","trace[557024931] 'applied index is now lower than readState.Index' (duration: 2.48173ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:51.963096Z","caller":"traceutil/trace.go:171","msg":"trace[481863523] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"163.607938ms","start":"2026-02-25T09:57:51.799446Z","end":"2026-02-25T09:57:51.963054Z","steps":["trace[481863523] 'process raft request' (duration: 161.107047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:51.96326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.669777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:51.963342Z","caller":"traceutil/trace.go:171","msg":"trace[1388772229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"161.847384ms","start":"2026-02-25T09:57:51.801448Z","end":"2026-02-25T09:57:51.963295Z","steps":["trace[1388772229] 'agreement among raft nodes before linearized reading' (duration: 161.647366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.319318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.8757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:57:52.31946Z","caller":"traceutil/trace.go:171","msg":"trace[1549790711] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:620; }","duration":"356.376589ms","start":"2026-02-25T09:57:51.963074Z","end":"2026-02-25T09:57:52.319451Z","steps":["trace[1549790711] 'read index received' (duration: 133.327104ms)","trace[1549790711] 'applied index is now lower than readState.Index' (duration: 223.049035ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:52.319506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.672667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.319522Z","caller":"traceutil/trace.go:171","msg":"trace[1192115271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"516.700958ms","start":"2026-02-25T09:57:51.802817Z","end":"2026-02-25T09:57:52.319518Z","steps":["trace[1192115271] 'agreement among raft nodes before linearized reading' (duration: 516.664536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.319538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:51.802812Z","time spent":"516.723119ms","remote":"127.0.0.1:49286","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-25T09:57:52.319678Z","caller":"traceutil/trace.go:171","msg":"trace[1005314946] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"516.557772ms","start":"2026-02-25T09:57:51.803093Z","end":"2026-02-25T09:57:52.31965Z","steps":["trace[1005314946] 'process raft request' (duration: 516.312194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.319734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.005649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.319751Z","caller":"traceutil/trace.go:171","msg":"trace[1038051521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:598; }","duration":"512.02053ms","start":"2026-02-25T09:57:51.807725Z","end":"2026-02-25T09:57:52.319746Z","steps":["trace[1038051521] 'agreement among raft nodes before linearized reading' (duration: 511.997799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.319769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:51.807707Z","time spent":"512.059771ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T09:57:52.319837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:51.803087Z","time spent":"516.665777ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:52.319713Z","caller":"traceutil/trace.go:171","msg":"trace[575532993] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"512.140123ms","start":"2026-02-25T09:57:51.807566Z","end":"2026-02-25T09:57:52.319706Z","steps":["trace[575532993] 'process raft request' (duration: 511.855323ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:52.319895Z","caller":"traceutil/trace.go:171","msg":"trace[1846170051] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"518.347718ms","start":"2026-02-25T09:57:51.801515Z","end":"2026-02-25T09:57:52.319863Z","steps":["trace[1846170051] 'process raft request' (duration: 294.874447ms)","trace[1846170051] 'compare' (duration: 222.699724ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:52.319955Z","caller":"traceutil/trace.go:171","msg":"trace[31597847] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"518.361998ms","start":"2026-02-25T09:57:51.801563Z","end":"2026-02-25T09:57:52.319925Z","steps":["trace[31597847] 'process raft request' (duration: 517.811219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.320053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:51.807548Z","time spent":"512.405893ms","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17558,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:52.320069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:51.8015Z","time spent":"518.488293ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:52.320093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:51.801543Z","time spent":"518.480553ms","remote":"127.0.0.1:49570","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-25T09:57:52.326011Z","caller":"traceutil/trace.go:171","msg":"trace[1630461277] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"299.599533ms","start":"2026-02-25T09:57:52.026385Z","end":"2026-02-25T09:57:52.325985Z","steps":["trace[1630461277] 'process raft request' (duration: 299.493679ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:52.588539Z","caller":"traceutil/trace.go:171","msg":"trace[735544761] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"229.351111ms","start":"2026-02-25T09:57:52.359167Z","end":"2026-02-25T09:57:52.588505Z","steps":["trace[735544761] 'read index received' (duration: 192.13412ms)","trace[735544761] 'applied index is now lower than readState.Index' (duration: 37.20274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:52.588833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.661663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T09:57:52.588863Z","caller":"traceutil/trace.go:171","msg":"trace[1383033957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:601; }","duration":"229.728815ms","start":"2026-02-25T09:57:52.359128Z","end":"2026-02-25T09:57:52.588857Z","steps":["trace[1383033957] 'agreement among raft nodes before linearized reading' (duration: 229.625441ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:52.588971Z","caller":"traceutil/trace.go:171","msg":"trace[1086650318] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"248.797827ms","start":"2026-02-25T09:57:52.340165Z","end":"2026-02-25T09:57:52.588963Z","steps":["trace[1086650318] 'process raft request' (duration: 211.276665ms)","trace[1086650318] 'compare' (duration: 36.853008ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:52.588828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.50996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.589076Z","caller":"traceutil/trace.go:171","msg":"trace[1007192101] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"226.79873ms","start":"2026-02-25T09:57:52.362268Z","end":"2026-02-25T09:57:52.589067Z","steps":["trace[1007192101] 'agreement among raft nodes before linearized reading' (duration: 226.50165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.589107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.286047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.58915Z","caller":"traceutil/trace.go:171","msg":"trace[2105348583] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:601; }","duration":"187.35823ms","start":"2026-02-25T09:57:52.401782Z","end":"2026-02-25T09:57:52.58914Z","steps":["trace[2105348583] 'agreement among raft nodes before linearized reading' (duration: 187.296878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.589378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.427399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.589417Z","caller":"traceutil/trace.go:171","msg":"trace[215469828] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"190.494311ms","start":"2026-02-25T09:57:52.398913Z","end":"2026-02-25T09:57:52.589407Z","steps":["trace[215469828] 'agreement among raft nodes before linearized reading' (duration: 190.434559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.589383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.527537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T09:57:52.589498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.560678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.589534Z","caller":"traceutil/trace.go:171","msg":"trace[360225835] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"222.61877ms","start":"2026-02-25T09:57:52.366907Z","end":"2026-02-25T09:57:52.589526Z","steps":["trace[360225835] 'agreement among raft nodes before linearized reading' (duration: 222.571678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.589558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.342901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.58962Z","caller":"traceutil/trace.go:171","msg":"trace[1004095425] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"215.415993ms","start":"2026-02-25T09:57:52.374196Z","end":"2026-02-25T09:57:52.589612Z","steps":["trace[1004095425] 'agreement among raft nodes before linearized reading' (duration: 215.320699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.589747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.106072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T09:57:52.589792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.050016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.589818Z","caller":"traceutil/trace.go:171","msg":"trace[1205442966] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"216.092087ms","start":"2026-02-25T09:57:52.373719Z","end":"2026-02-25T09:57:52.589811Z","steps":["trace[1205442966] 'agreement among raft nodes before linearized reading' (duration: 216.059136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.589908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.224896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.589947Z","caller":"traceutil/trace.go:171","msg":"trace[1987722177] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"222.282209ms","start":"2026-02-25T09:57:52.367655Z","end":"2026-02-25T09:57:52.589938Z","steps":["trace[1987722177] 'agreement among raft nodes before linearized reading' (duration: 221.974817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.590068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.878029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.590099Z","caller":"traceutil/trace.go:171","msg":"trace[826430114] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"220.933511ms","start":"2026-02-25T09:57:52.369156Z","end":"2026-02-25T09:57:52.59009Z","steps":["trace[826430114] 'agreement among raft nodes before linearized reading' (duration: 220.888359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:52.589763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.022349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:52.590416Z","caller":"traceutil/trace.go:171","msg":"trace[1714531637] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"222.710185ms","start":"2026-02-25T09:57:52.367697Z","end":"2026-02-25T09:57:52.590407Z","steps":["trace[1714531637] 'agreement among raft nodes before linearized reading' (duration: 222.0451ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:52.589795Z","caller":"traceutil/trace.go:171","msg":"trace[4034647] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"222.174944ms","start":"2026-02-25T09:57:52.367613Z","end":"2026-02-25T09:57:52.589788Z","steps":["trace[4034647] 'agreement among raft nodes before linearized reading' (duration: 222.117672ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:52.589496Z","caller":"traceutil/trace.go:171","msg":"trace[883839466] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:601; }","duration":"215.646441ms","start":"2026-02-25T09:57:52.373831Z","end":"2026-02-25T09:57:52.589477Z","steps":["trace[883839466] 'agreement among raft nodes before linearized reading' (duration: 215.492555ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:52.997614Z","caller":"traceutil/trace.go:171","msg":"trace[179633228] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"222.687802ms","start":"2026-02-25T09:57:52.7749Z","end":"2026-02-25T09:57:52.997587Z","steps":["trace[179633228] 'process raft request' (duration: 155.407187ms)","trace[179633228] 'compare' (duration: 67.150631ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:53.045479Z","caller":"traceutil/trace.go:171","msg":"trace[428382659] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"252.594666ms","start":"2026-02-25T09:57:52.79284Z","end":"2026-02-25T09:57:53.045435Z","steps":["trace[428382659] 'process raft request' (duration: 252.327617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:53.432913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.417499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:57:53.433105Z","caller":"traceutil/trace.go:171","msg":"trace[910088471] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"466.82067ms","start":"2026-02-25T09:57:52.966263Z","end":"2026-02-25T09:57:53.433084Z","steps":["trace[910088471] 'process raft request' (duration: 299.155713ms)","trace[910088471] 'compare' (duration: 167.201711ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:57:53.433175Z","caller":"traceutil/trace.go:171","msg":"trace[35104634] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"427.145373ms","start":"2026-02-25T09:57:53.006019Z","end":"2026-02-25T09:57:53.433164Z","steps":["trace[35104634] 'process raft request' (duration: 427.006027ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:53.433164Z","caller":"traceutil/trace.go:171","msg":"trace[1590283280] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"461.22917ms","start":"2026-02-25T09:57:52.971882Z","end":"2026-02-25T09:57:53.433111Z","steps":["trace[1590283280] 'read index received' (duration: 73.182961ms)","trace[1590283280] 'applied index is now lower than readState.Index' (duration: 388.045089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:53.433199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:52.966229Z","time spent":"466.925635ms","remote":"127.0.0.1:49328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T09:57:53.433233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:53.005995Z","time spent":"427.205745ms","remote":"127.0.0.1:49756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:57:53.433361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.46555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T09:57:53.433415Z","caller":"traceutil/trace.go:171","msg":"trace[693800030] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:607; }","duration":"461.539283ms","start":"2026-02-25T09:57:52.971861Z","end":"2026-02-25T09:57:53.4334Z","steps":["trace[693800030] 'agreement among raft nodes before linearized reading' (duration: 461.369197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:53.433466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:52.971853Z","time spent":"461.599654ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-25T09:57:53.585396Z","caller":"traceutil/trace.go:171","msg":"trace[1192904632] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:633; }","duration":"152.170839ms","start":"2026-02-25T09:57:53.433203Z","end":"2026-02-25T09:57:53.585374Z","steps":["trace[1192904632] 'read index received' (duration: 142.244598ms)","trace[1192904632] 'applied index is now lower than readState.Index' (duration: 9.925391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:57:53.585527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.290052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:57:53.585581Z","caller":"traceutil/trace.go:171","msg":"trace[638229883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"482.394025ms","start":"2026-02-25T09:57:53.103176Z","end":"2026-02-25T09:57:53.58557Z","steps":["trace[638229883] 'agreement among raft nodes before linearized reading' (duration: 482.285821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:53.585614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:53.103152Z","time spent":"482.453587ms","remote":"127.0.0.1:49292","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-25T09:57:53.585847Z","caller":"traceutil/trace.go:171","msg":"trace[732169889] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"208.18327ms","start":"2026-02-25T09:57:53.377653Z","end":"2026-02-25T09:57:53.585837Z","steps":["trace[732169889] 'process raft request' (duration: 207.637181ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:53.585869Z","caller":"traceutil/trace.go:171","msg":"trace[685324408] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"327.473838ms","start":"2026-02-25T09:57:53.258331Z","end":"2026-02-25T09:57:53.585804Z","steps":["trace[685324408] 'process raft request' (duration: 326.886978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:53.585997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:53.258302Z","time spent":"327.634304ms","remote":"127.0.0.1:49356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T09:57:53.58606Z","caller":"traceutil/trace.go:171","msg":"trace[2037370427] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"124.232572ms","start":"2026-02-25T09:57:53.461818Z","end":"2026-02-25T09:57:53.58605Z","steps":["trace[2037370427] 'process raft request' (duration: 123.505766ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:57:53.586201Z","caller":"traceutil/trace.go:171","msg":"trace[1714312567] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"519.235708ms","start":"2026-02-25T09:57:53.06695Z","end":"2026-02-25T09:57:53.586186Z","steps":["trace[1714312567] 'process raft request' (duration: 508.423165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:53.586266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:53.066925Z","time spent":"519.3091ms","remote":"127.0.0.1:49830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T09:57:53.586536Z","caller":"traceutil/trace.go:171","msg":"trace[541612707] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"417.123267ms","start":"2026-02-25T09:57:53.169402Z","end":"2026-02-25T09:57:53.586525Z","steps":["trace[541612707] 'process raft request' (duration: 415.785121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:53.586603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:53.16935Z","time spent":"417.210831ms","remote":"127.0.0.1:49328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T09:57:53.586715Z","caller":"traceutil/trace.go:171","msg":"trace[379649325] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"468.879617ms","start":"2026-02-25T09:57:53.117826Z","end":"2026-02-25T09:57:53.586706Z","steps":["trace[379649325] 'process raft request' (duration: 467.214248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:57:53.586772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:57:53.117802Z","time spent":"468.936609ms","remote":"127.0.0.1:49328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T09:57:54.661771Z","caller":"traceutil/trace.go:171","msg":"trace[2099134403] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"153.369732ms","start":"2026-02-25T09:57:54.508369Z","end":"2026-02-25T09:57:54.661739Z","steps":["trace[2099134403] 'process raft request' (duration: 151.88369ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:03.232716Z","caller":"traceutil/trace.go:171","msg":"trace[514825087] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"143.893497ms","start":"2026-02-25T09:58:03.088804Z","end":"2026-02-25T09:58:03.232697Z","steps":["trace[514825087] 'process raft request' (duration: 143.773843ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:03.489188Z","caller":"traceutil/trace.go:171","msg":"trace[1821974738] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"249.824229ms","start":"2026-02-25T09:58:03.239337Z","end":"2026-02-25T09:58:03.489161Z","steps":["trace[1821974738] 'process raft request' (duration: 226.318286ms)","trace[1821974738] 'compare' (duration: 23.389ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:03.721803Z","caller":"traceutil/trace.go:171","msg":"trace[291471737] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"225.250112ms","start":"2026-02-25T09:58:03.496533Z","end":"2026-02-25T09:58:03.721783Z","steps":["trace[291471737] 'process raft request' (duration: 186.452503ms)","trace[291471737] 'compare' (duration: 38.668295ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:16.747814Z","caller":"traceutil/trace.go:171","msg":"trace[44195829] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"293.051355ms","start":"2026-02-25T09:58:16.454747Z","end":"2026-02-25T09:58:16.747798Z","steps":["trace[44195829] 'read index received' (duration: 292.85987ms)","trace[44195829] 'applied index is now lower than readState.Index' (duration: 190.945µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:16.747891Z","caller":"traceutil/trace.go:171","msg":"trace[1823005334] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"308.008518ms","start":"2026-02-25T09:58:16.439876Z","end":"2026-02-25T09:58:16.747884Z","steps":["trace[1823005334] 'process raft request' (duration: 307.801513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:16.747953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:16.43985Z","time spent":"308.05547ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:58:16.748068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.318742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T09:58:16.748085Z","caller":"traceutil/trace.go:171","msg":"trace[1373743311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"293.355054ms","start":"2026-02-25T09:58:16.454725Z","end":"2026-02-25T09:58:16.74808Z","steps":["trace[1373743311] 'agreement among raft nodes before linearized reading' (duration: 293.287182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:16.748236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.283821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T09:58:16.748278Z","caller":"traceutil/trace.go:171","msg":"trace[1519469969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"293.332753ms","start":"2026-02-25T09:58:16.454932Z","end":"2026-02-25T09:58:16.748265Z","steps":["trace[1519469969] 'agreement among raft nodes before linearized reading' (duration: 293.22978ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:20.992508Z","caller":"traceutil/trace.go:171","msg":"trace[846447172] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"164.42292ms","start":"2026-02-25T09:58:20.828063Z","end":"2026-02-25T09:58:20.992485Z","steps":["trace[846447172] 'process raft request' (duration: 164.196474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:25.235763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.380843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:58:25.235878Z","caller":"traceutil/trace.go:171","msg":"trace[1441765971] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"204.308023ms","start":"2026-02-25T09:58:25.031546Z","end":"2026-02-25T09:58:25.235854Z","steps":["trace[1441765971] 'process raft request' (duration: 86.756516ms)","trace[1441765971] 'compare' (duration: 117.26846ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:25.242758Z","caller":"traceutil/trace.go:171","msg":"trace[1045359111] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"210.632623ms","start":"2026-02-25T09:58:25.032107Z","end":"2026-02-25T09:58:25.242739Z","steps":["trace[1045359111] 'process raft request' (duration: 210.609943ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:25.242876Z","caller":"traceutil/trace.go:171","msg":"trace[54293800] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"210.983053ms","start":"2026-02-25T09:58:25.03185Z","end":"2026-02-25T09:58:25.242833Z","steps":["trace[54293800] 'process raft request' (duration: 210.770567ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:27.420245Z","caller":"traceutil/trace.go:171","msg":"trace[1448839540] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"128.411421ms","start":"2026-02-25T09:58:27.291819Z","end":"2026-02-25T09:58:27.42023Z","steps":["trace[1448839540] 'process raft request' (duration: 128.328129ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:27.421358Z","caller":"traceutil/trace.go:171","msg":"trace[1850804441] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"129.033646ms","start":"2026-02-25T09:58:27.292304Z","end":"2026-02-25T09:58:27.421338Z","steps":["trace[1850804441] 'process raft request' (duration: 128.918453ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:29.578053Z","caller":"traceutil/trace.go:171","msg":"trace[876397278] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"103.986476ms","start":"2026-02-25T09:58:29.474037Z","end":"2026-02-25T09:58:29.578024Z","steps":["trace[876397278] 'process raft request' (duration: 87.416442ms)","trace[876397278] 'compare' (duration: 16.453571ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:29.757575Z","caller":"traceutil/trace.go:171","msg":"trace[1585433840] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"154.390485ms","start":"2026-02-25T09:58:29.60316Z","end":"2026-02-25T09:58:29.757551Z","steps":["trace[1585433840] 'process raft request' (duration: 153.211367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:33.652463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.924976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T09:58:33.652547Z","caller":"traceutil/trace.go:171","msg":"trace[1547972032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"139.030668ms","start":"2026-02-25T09:58:33.513498Z","end":"2026-02-25T09:58:33.652529Z","steps":["trace[1547972032] 'range keys from in-memory index tree' (duration: 138.70163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:33.652896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.350434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T09:58:33.652935Z","caller":"traceutil/trace.go:171","msg":"trace[1475662671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"295.428986ms","start":"2026-02-25T09:58:33.357497Z","end":"2026-02-25T09:58:33.652926Z","steps":["trace[1475662671] 'range keys from in-memory index tree' (duration: 295.220611ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:33.762161Z","caller":"traceutil/trace.go:171","msg":"trace[1747669934] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"104.748434ms","start":"2026-02-25T09:58:33.65738Z","end":"2026-02-25T09:58:33.762129Z","steps":["trace[1747669934] 'process raft request' (duration: 104.302322ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:33.796691Z","caller":"traceutil/trace.go:171","msg":"trace[1119311455] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"138.365694ms","start":"2026-02-25T09:58:33.658297Z","end":"2026-02-25T09:58:33.796662Z","steps":["trace[1119311455] 'process raft request' (duration: 138.272252ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:33.796734Z","caller":"traceutil/trace.go:171","msg":"trace[465807613] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"139.132351ms","start":"2026-02-25T09:58:33.657577Z","end":"2026-02-25T09:58:33.796709Z","steps":["trace[465807613] 'process raft request' (duration: 138.769213ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:48.658021Z","caller":"traceutil/trace.go:171","msg":"trace[1641465977] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"184.671657ms","start":"2026-02-25T09:58:48.473329Z","end":"2026-02-25T09:58:48.658001Z","steps":["trace[1641465977] 'process raft request' (duration: 184.564965ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:50.363153Z","caller":"traceutil/trace.go:171","msg":"trace[1051151400] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"151.452791ms","start":"2026-02-25T09:58:50.211683Z","end":"2026-02-25T09:58:50.363136Z","steps":["trace[1051151400] 'process raft request' (duration: 151.348939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:50.884628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.991594ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T09:58:50.884746Z","caller":"traceutil/trace.go:171","msg":"trace[1377861381] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"272.951753ms","start":"2026-02-25T09:58:50.611774Z","end":"2026-02-25T09:58:50.884726Z","steps":["trace[1377861381] 'read index received' (duration: 23.911µs)","trace[1377861381] 'applied index is now lower than readState.Index' (duration: 272.926152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:58:50.885054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.252789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4913"} {"level":"info","ts":"2026-02-25T09:58:50.885136Z","caller":"traceutil/trace.go:171","msg":"trace[27142392] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:898; }","duration":"273.380152ms","start":"2026-02-25T09:58:50.611741Z","end":"2026-02-25T09:58:50.885121Z","steps":["trace[27142392] 'agreement among raft nodes before linearized reading' (duration: 273.143727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:50.943106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.480486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T09:58:50.943158Z","caller":"traceutil/trace.go:171","msg":"trace[1884005941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"281.562817ms","start":"2026-02-25T09:58:50.661581Z","end":"2026-02-25T09:58:50.943144Z","steps":["trace[1884005941] 'agreement among raft nodes before linearized reading' (duration: 281.434695ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:50.943178Z","caller":"traceutil/trace.go:171","msg":"trace[125063009] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"317.014389ms","start":"2026-02-25T09:58:50.626126Z","end":"2026-02-25T09:58:50.94314Z","steps":["trace[125063009] 'process raft request' (duration: 316.722884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:58:50.943554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:50.62611Z","time spent":"317.154282ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:58:51.402819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.007946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T09:58:51.402899Z","caller":"traceutil/trace.go:171","msg":"trace[629758947] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"297.840631ms","start":"2026-02-25T09:58:51.105043Z","end":"2026-02-25T09:58:51.402884Z","steps":["trace[629758947] 'read index received' (duration: 42.761µs)","trace[629758947] 'applied index is now lower than readState.Index' (duration: 297.79692ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:58:51.402964Z","caller":"traceutil/trace.go:171","msg":"trace[930403584] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"456.923069ms","start":"2026-02-25T09:58:50.946031Z","end":"2026-02-25T09:58:51.402954Z","steps":["trace[930403584] 'process raft request' (duration: 46.71409ms)","trace[930403584] 'compare' (duration: 409.919754ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:58:51.403004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:58:50.94601Z","time spent":"456.97168ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:58:51.403147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.110386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:58:51.403166Z","caller":"traceutil/trace.go:171","msg":"trace[1704618094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"298.129766ms","start":"2026-02-25T09:58:51.10503Z","end":"2026-02-25T09:58:51.40316Z","steps":["trace[1704618094] 'agreement among raft nodes before linearized reading' (duration: 298.095726ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:58:55.540844Z","caller":"traceutil/trace.go:171","msg":"trace[1661107479] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"115.776404ms","start":"2026-02-25T09:58:55.425034Z","end":"2026-02-25T09:58:55.540811Z","steps":["trace[1661107479] 'process raft request' (duration: 115.388036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:04.501592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4092255371876100448,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T09:59:04.707349Z","caller":"traceutil/trace.go:171","msg":"trace[484423667] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"706.725501ms","start":"2026-02-25T09:59:04.000604Z","end":"2026-02-25T09:59:04.70733Z","steps":["trace[484423667] 'read index received' (duration: 706.546428ms)","trace[484423667] 'applied index is now lower than readState.Index' (duration: 178.153µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:59:04.707408Z","caller":"traceutil/trace.go:171","msg":"trace[630256077] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"758.678058ms","start":"2026-02-25T09:59:03.948718Z","end":"2026-02-25T09:59:04.707396Z","steps":["trace[630256077] 'process raft request' (duration: 758.511475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:04.707528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.933034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T09:59:04.707551Z","caller":"traceutil/trace.go:171","msg":"trace[507439348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"707.017536ms","start":"2026-02-25T09:59:04.000528Z","end":"2026-02-25T09:59:04.707545Z","steps":["trace[507439348] 'agreement among raft nodes before linearized reading' (duration: 706.910254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:04.707582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:59:04.000475Z","time spent":"707.103597ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T09:59:04.707705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:59:03.948702Z","time spent":"758.74118ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T09:59:04.707979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.046719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T09:59:04.708105Z","caller":"traceutil/trace.go:171","msg":"trace[1077554346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"706.392234ms","start":"2026-02-25T09:59:04.001691Z","end":"2026-02-25T09:59:04.708083Z","steps":["trace[1077554346] 'agreement among raft nodes before linearized reading' (duration: 705.947656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:04.708131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.533213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cca12116-e935-4ceb-bbf0-61d134e14f6a\" ","response":"range_response_count:1 size:3817"} {"level":"warn","ts":"2026-02-25T09:59:04.708165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:59:04.001679Z","time spent":"706.471885ms","remote":"127.0.0.1:49570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T09:59:04.708221Z","caller":"traceutil/trace.go:171","msg":"trace[1504571289] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cca12116-e935-4ceb-bbf0-61d134e14f6a; range_end:; response_count:1; response_revision:955; }","duration":"462.655205ms","start":"2026-02-25T09:59:04.245543Z","end":"2026-02-25T09:59:04.708198Z","steps":["trace[1504571289] 'agreement among raft nodes before linearized reading' (duration: 462.196507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:04.708285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:59:04.245532Z","time spent":"462.744297ms","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3840,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cca12116-e935-4ceb-bbf0-61d134e14f6a\" "} {"level":"warn","ts":"2026-02-25T09:59:04.708762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.230697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-02-25T09:59:04.708978Z","caller":"traceutil/trace.go:171","msg":"trace[1115487085] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:955; }","duration":"515.490761ms","start":"2026-02-25T09:59:04.193478Z","end":"2026-02-25T09:59:04.708969Z","steps":["trace[1115487085] 'agreement among raft nodes before linearized reading' (duration: 515.205496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:04.708997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:59:04.193456Z","time spent":"515.536683ms","remote":"127.0.0.1:49468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1513,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-25T09:59:15.839091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.311648ms","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-25T09:59:15.839176Z","caller":"traceutil/trace.go:171","msg":"trace[194396153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"135.44667ms","start":"2026-02-25T09:59:15.703711Z","end":"2026-02-25T09:59:15.839158Z","steps":["trace[194396153] 'range keys from in-memory index tree' (duration: 135.059004ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:59:16.990748Z","caller":"traceutil/trace.go:171","msg":"trace[1201731480] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"179.339045ms","start":"2026-02-25T09:59:16.811385Z","end":"2026-02-25T09:59:16.990724Z","steps":["trace[1201731480] 'read index received' (duration: 179.193863ms)","trace[1201731480] 'applied index is now lower than readState.Index' (duration: 143.852µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T09:59:16.990839Z","caller":"traceutil/trace.go:171","msg":"trace[400987624] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"230.403557ms","start":"2026-02-25T09:59:16.760414Z","end":"2026-02-25T09:59:16.990818Z","steps":["trace[400987624] 'process raft request' (duration: 230.166303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:16.991217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.983141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T09:59:16.991265Z","caller":"traceutil/trace.go:171","msg":"trace[1660188910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1008; }","duration":"172.078322ms","start":"2026-02-25T09:59:16.819176Z","end":"2026-02-25T09:59:16.991254Z","steps":["trace[1660188910] 'agreement among raft nodes before linearized reading' (duration: 171.94793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:16.991394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.578379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T09:59:16.991431Z","caller":"traceutil/trace.go:171","msg":"trace[25898837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1008; }","duration":"180.081678ms","start":"2026-02-25T09:59:16.81134Z","end":"2026-02-25T09:59:16.991422Z","steps":["trace[25898837] 'agreement among raft nodes before linearized reading' (duration: 179.531699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:17.212283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.816335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T09:59:17.212356Z","caller":"traceutil/trace.go:171","msg":"trace[2133223859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"111.986607ms","start":"2026-02-25T09:59:17.100354Z","end":"2026-02-25T09:59:17.21234Z","steps":["trace[2133223859] 'range keys from in-memory index tree' (duration: 111.713883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:23.993207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.701985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-02-25T09:59:23.993343Z","caller":"traceutil/trace.go:171","msg":"trace[1018724757] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1032; }","duration":"121.865899ms","start":"2026-02-25T09:59:23.871456Z","end":"2026-02-25T09:59:23.993322Z","steps":["trace[1018724757] 'range keys from in-memory index tree' (duration: 120.987474ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:59:27.508368Z","caller":"traceutil/trace.go:171","msg":"trace[427646748] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"302.153836ms","start":"2026-02-25T09:59:27.206185Z","end":"2026-02-25T09:59:27.508339Z","steps":["trace[427646748] 'process raft request' (duration: 252.565405ms)","trace[427646748] 'compare' (duration: 49.463319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:59:27.508591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:59:27.206161Z","time spent":"302.301709ms","remote":"127.0.0.1:49570","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-25T09:59:27.522222Z","caller":"traceutil/trace.go:171","msg":"trace[1410744461] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1090; }","duration":"279.752623ms","start":"2026-02-25T09:59:27.24244Z","end":"2026-02-25T09:59:27.522193Z","steps":["trace[1410744461] 'read index received' (duration: 216.296763ms)","trace[1410744461] 'applied index is now lower than readState.Index' (duration: 63.45457ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T09:59:27.522393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.932306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:780"} {"level":"info","ts":"2026-02-25T09:59:27.522459Z","caller":"traceutil/trace.go:171","msg":"trace[1903911561] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:1042; }","duration":"280.042678ms","start":"2026-02-25T09:59:27.242402Z","end":"2026-02-25T09:59:27.522445Z","steps":["trace[1903911561] 'agreement among raft nodes before linearized reading' (duration: 279.927056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:27.522507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.191947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:certificate-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-25T09:59:27.522595Z","caller":"traceutil/trace.go:171","msg":"trace[1029267843] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1042; }","duration":"219.323969ms","start":"2026-02-25T09:59:27.303258Z","end":"2026-02-25T09:59:27.522582Z","steps":["trace[1029267843] 'agreement among raft nodes before linearized reading' (duration: 219.181887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:27.522514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.786516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T09:59:27.522774Z","caller":"traceutil/trace.go:171","msg":"trace[1888959949] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1042; }","duration":"258.099002ms","start":"2026-02-25T09:59:27.264651Z","end":"2026-02-25T09:59:27.52275Z","steps":["trace[1888959949] 'agreement among raft nodes before linearized reading' (duration: 257.754216ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T09:59:27.522371Z","caller":"traceutil/trace.go:171","msg":"trace[56762514] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"315.430006ms","start":"2026-02-25T09:59:27.206885Z","end":"2026-02-25T09:59:27.522315Z","steps":["trace[56762514] 'process raft request' (duration: 315.107501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:27.523046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T09:59:27.206873Z","time spent":"316.091847ms","remote":"127.0.0.1:49570","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-25T09:59:27.861292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.50599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:723"} {"level":"info","ts":"2026-02-25T09:59:27.861353Z","caller":"traceutil/trace.go:171","msg":"trace[317794122] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1042; }","duration":"170.624403ms","start":"2026-02-25T09:59:27.690713Z","end":"2026-02-25T09:59:27.861338Z","steps":["trace[317794122] 'range keys from in-memory index tree' (duration: 170.285988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T09:59:27.86148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.113349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-02-25T09:59:27.861573Z","caller":"traceutil/trace.go:171","msg":"trace[1530259988] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1042; }","duration":"247.226261ms","start":"2026-02-25T09:59:27.614327Z","end":"2026-02-25T09:59:27.861553Z","steps":["trace[1530259988] 'range keys from in-memory index tree' (duration: 246.990907ms)"],"step_count":1}