{"level":"warn","ts":"2026-02-25T02:07:17.395851Z","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-25T02:07:17.395943Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-25T02:07:17.396018Z","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-25T02:07:17.396027Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-02-25T02:07:17.396045Z","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-25T02:07:17.396684Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-02-25T02:07:17.396758Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-25T02:07:17.40664Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.655917ms"} {"level":"info","ts":"2026-02-25T02:07:17.413147Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-02-25T02:07:17.413278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:07:17.413313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-02-25T02:07:17.413332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:07:17.413345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-02-25T02:07:17.413406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-02-25T02:07:17.424471Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:07:17.42692Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:07:17.427846Z","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-25T02:07:17.430629Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:07:17.430806Z","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-25T02:07:17.430953Z","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-25T02:07:17.430967Z","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-25T02:07:17.430958Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:07:17.432339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-02-25T02:07:17.434136Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-02-25T02:07:17.436134Z","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-25T02:07:17.436299Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-02-25T02:07:17.436365Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-02-25T02:07:17.436377Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:07:17.436432Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:07:18.214063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:07:18.214125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:07:18.214144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-02-25T02:07:18.214158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:07:18.214166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-02-25T02:07:18.214176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-02-25T02:07:18.214185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-02-25T02:07:18.215179Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:18.215192Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:07:18.21546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:07:18.215499Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:07:18.216352Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:07:18.216379Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:07:18.217272Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:18.217424Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:18.217472Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:18.218451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-02-25T02:07:18.21932Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:07:23.783996Z","caller":"traceutil/trace.go:171","msg":"trace[270133798] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"106.802144ms","start":"2026-02-25T02:07:23.677171Z","end":"2026-02-25T02:07:23.783974Z","steps":["trace[270133798] 'process raft request' (duration: 106.644731ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.007251Z","caller":"traceutil/trace.go:171","msg":"trace[269004294] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:278; }","duration":"277.364057ms","start":"2026-02-25T02:07:23.729864Z","end":"2026-02-25T02:07:24.007228Z","steps":["trace[269004294] 'read index received' (duration: 53.970729ms)","trace[269004294] 'applied index is now lower than readState.Index' (duration: 223.392428ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:24.00737Z","caller":"traceutil/trace.go:171","msg":"trace[993807441] transaction","detail":"{read_only:false; number_of_response:0; response_revision:271; }","duration":"250.135692ms","start":"2026-02-25T02:07:23.757227Z","end":"2026-02-25T02:07:24.007363Z","steps":["trace[993807441] 'process raft request' (duration: 249.870587ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.007464Z","caller":"traceutil/trace.go:171","msg":"trace[913723279] transaction","detail":"{read_only:false; number_of_response:0; response_revision:271; }","duration":"248.6274ms","start":"2026-02-25T02:07:23.758805Z","end":"2026-02-25T02:07:24.007433Z","steps":["trace[913723279] 'process raft request' (duration: 248.398955ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.007546Z","caller":"traceutil/trace.go:171","msg":"trace[1642749391] transaction","detail":"{read_only:false; number_of_response:0; response_revision:271; }","duration":"249.339855ms","start":"2026-02-25T02:07:23.758152Z","end":"2026-02-25T02:07:24.007492Z","steps":["trace[1642749391] 'process raft request' (duration: 249.010638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.007602Z","caller":"traceutil/trace.go:171","msg":"trace[2064435742] transaction","detail":"{read_only:false; number_of_response:0; response_revision:271; }","duration":"248.879176ms","start":"2026-02-25T02:07:23.758648Z","end":"2026-02-25T02:07:24.007527Z","steps":["trace[2064435742] 'process raft request' (duration: 248.537599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.007734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.859518ms","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-25T02:07:24.007798Z","caller":"traceutil/trace.go:171","msg":"trace[152302282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:271; }","duration":"277.98229ms","start":"2026-02-25T02:07:23.729805Z","end":"2026-02-25T02:07:24.007787Z","steps":["trace[152302282] 'agreement among raft nodes before linearized reading' (duration: 277.735545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.007825Z","caller":"traceutil/trace.go:171","msg":"trace[572069764] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"288.389564ms","start":"2026-02-25T02:07:23.71879Z","end":"2026-02-25T02:07:24.00718Z","steps":["trace[572069764] 'process raft request' (duration: 287.914014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.007833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.579712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-02-25T02:07:24.007906Z","caller":"traceutil/trace.go:171","msg":"trace[361632917] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:271; }","duration":"251.704355ms","start":"2026-02-25T02:07:23.756186Z","end":"2026-02-25T02:07:24.00789Z","steps":["trace[361632917] 'agreement among raft nodes before linearized reading' (duration: 251.522162ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.209184Z","caller":"traceutil/trace.go:171","msg":"trace[34236129] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"184.8604ms","start":"2026-02-25T02:07:24.024299Z","end":"2026-02-25T02:07:24.20916Z","steps":["trace[34236129] 'process raft request' (duration: 181.131621ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.232506Z","caller":"traceutil/trace.go:171","msg":"trace[2017537148] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:285; }","duration":"152.922915ms","start":"2026-02-25T02:07:24.079547Z","end":"2026-02-25T02:07:24.23247Z","steps":["trace[2017537148] 'read index received' (duration: 125.958745ms)","trace[2017537148] 'applied index is now lower than readState.Index' (duration: 26.96329ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:24.232629Z","caller":"traceutil/trace.go:171","msg":"trace[1177157278] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"190.624494ms","start":"2026-02-25T02:07:24.041995Z","end":"2026-02-25T02:07:24.232619Z","steps":["trace[1177157278] 'process raft request' (duration: 190.372058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:24.232939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.383135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:24.23301Z","caller":"traceutil/trace.go:171","msg":"trace[340538534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:274; }","duration":"153.506808ms","start":"2026-02-25T02:07:24.079484Z","end":"2026-02-25T02:07:24.232991Z","steps":["trace[340538534] 'agreement among raft nodes before linearized reading' (duration: 153.405616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.393132Z","caller":"traceutil/trace.go:171","msg":"trace[83345232] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"114.875977ms","start":"2026-02-25T02:07:24.278221Z","end":"2026-02-25T02:07:24.393097Z","steps":["trace[83345232] 'process raft request' (duration: 114.779335ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.393147Z","caller":"traceutil/trace.go:171","msg":"trace[1381816331] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"115.089371ms","start":"2026-02-25T02:07:24.278013Z","end":"2026-02-25T02:07:24.393102Z","steps":["trace[1381816331] 'process raft request' (duration: 39.545189ms)","trace[1381816331] 'compare' (duration: 75.140464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:24.393898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.817817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"info","ts":"2026-02-25T02:07:24.393987Z","caller":"traceutil/trace.go:171","msg":"trace[1464581938] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:279; }","duration":"115.951429ms","start":"2026-02-25T02:07:24.278007Z","end":"2026-02-25T02:07:24.393958Z","steps":["trace[1464581938] 'agreement among raft nodes before linearized reading' (duration: 115.771626ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:24.39326Z","caller":"traceutil/trace.go:171","msg":"trace[913912024] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"115.187564ms","start":"2026-02-25T02:07:24.27805Z","end":"2026-02-25T02:07:24.393238Z","steps":["trace[913912024] 'read index received' (duration: 39.496609ms)","trace[913912024] 'applied index is now lower than readState.Index' (duration: 75.688525ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:28.30754Z","caller":"traceutil/trace.go:171","msg":"trace[141902507] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"253.924833ms","start":"2026-02-25T02:07:28.053589Z","end":"2026-02-25T02:07:28.307513Z","steps":["trace[141902507] 'process raft request' (duration: 253.77481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:28.924639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602844015896621,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:07:29.045518Z","caller":"traceutil/trace.go:171","msg":"trace[461852944] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:364; }","duration":"621.289043ms","start":"2026-02-25T02:07:28.424202Z","end":"2026-02-25T02:07:29.045491Z","steps":["trace[461852944] 'read index received' (duration: 542.049091ms)","trace[461852944] 'applied index is now lower than readState.Index' (duration: 79.238432ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:29.045566Z","caller":"traceutil/trace.go:171","msg":"trace[32715925] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"689.565021ms","start":"2026-02-25T02:07:28.355954Z","end":"2026-02-25T02:07:29.045519Z","steps":["trace[32715925] 'process raft request' (duration: 610.36967ms)","trace[32715925] 'compare' (duration: 78.944636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:29.04569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.465137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T02:07:29.045739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.126403ms","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-25T02:07:29.045754Z","caller":"traceutil/trace.go:171","msg":"trace[880981515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:350; }","duration":"621.57094ms","start":"2026-02-25T02:07:28.42417Z","end":"2026-02-25T02:07:29.045741Z","steps":["trace[880981515] 'agreement among raft nodes before linearized reading' (duration: 621.445457ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:29.045779Z","caller":"traceutil/trace.go:171","msg":"trace[1074368425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:350; }","duration":"237.207384ms","start":"2026-02-25T02:07:28.80856Z","end":"2026-02-25T02:07:29.045767Z","steps":["trace[1074368425] 'agreement among raft nodes before linearized reading' (duration: 237.086042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:29.045783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:28.42415Z","time spent":"621.62891ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:07:29.048518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:28.355931Z","time spent":"689.726234ms","remote":"127.0.0.1:32806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4241,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:29.389714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.227431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:29.389957Z","caller":"traceutil/trace.go:171","msg":"trace[49462081] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:365; }","duration":"337.423477ms","start":"2026-02-25T02:07:29.052512Z","end":"2026-02-25T02:07:29.389935Z","steps":["trace[49462081] 'read index received' (duration: 115.688984ms)","trace[49462081] 'applied index is now lower than readState.Index' (duration: 221.733203ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:29.390088Z","caller":"traceutil/trace.go:171","msg":"trace[1014562207] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"337.777745ms","start":"2026-02-25T02:07:29.052295Z","end":"2026-02-25T02:07:29.390073Z","steps":["trace[1014562207] 'process raft request' (duration: 337.566941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:29.390188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:29.052276Z","time spent":"337.860897ms","remote":"127.0.0.1:32892","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-25T02:07:29.390347Z","caller":"traceutil/trace.go:171","msg":"trace[1460928729] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"339.010781ms","start":"2026-02-25T02:07:29.051296Z","end":"2026-02-25T02:07:29.390307Z","steps":["trace[1460928729] 'process raft request' (duration: 116.89667ms)","trace[1460928729] 'compare' (duration: 220.917675ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:29.39041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.876067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"warn","ts":"2026-02-25T02:07:29.390507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:29.051276Z","time spent":"339.159494ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:29.390521Z","caller":"traceutil/trace.go:171","msg":"trace[385005589] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:352; }","duration":"337.9948ms","start":"2026-02-25T02:07:29.052484Z","end":"2026-02-25T02:07:29.390478Z","steps":["trace[385005589] 'agreement among raft nodes before linearized reading' (duration: 337.811276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:29.390332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.875304ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:29.390575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:29.052474Z","time spent":"338.088102ms","remote":"127.0.0.1:32806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4279,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-25T02:07:29.390595Z","caller":"traceutil/trace.go:171","msg":"trace[1017235407] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:352; }","duration":"282.1478ms","start":"2026-02-25T02:07:29.108425Z","end":"2026-02-25T02:07:29.390573Z","steps":["trace[1017235407] 'agreement among raft nodes before linearized reading' (duration: 281.791852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:30.043585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.15244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:30.043702Z","caller":"traceutil/trace.go:171","msg":"trace[1698540336] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"239.108225ms","start":"2026-02-25T02:07:29.804575Z","end":"2026-02-25T02:07:30.043684Z","steps":["trace[1698540336] 'read index received' (duration: 58.771µs)","trace[1698540336] 'applied index is now lower than readState.Index' (duration: 239.048414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:30.043771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.203807ms","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-25T02:07:30.043752Z","caller":"traceutil/trace.go:171","msg":"trace[708378217] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"645.899472ms","start":"2026-02-25T02:07:29.397804Z","end":"2026-02-25T02:07:30.043704Z","steps":["trace[708378217] 'process raft request' (duration: 282.538548ms)","trace[708378217] 'compare' (duration: 363.034247ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:30.043804Z","caller":"traceutil/trace.go:171","msg":"trace[461636887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"239.271218ms","start":"2026-02-25T02:07:29.804522Z","end":"2026-02-25T02:07:30.043793Z","steps":["trace[461636887] 'agreement among raft nodes before linearized reading' (duration: 239.208507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:30.043928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:29.397782Z","time spent":"646.051836ms","remote":"127.0.0.1:32806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4049,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:30.21023Z","caller":"traceutil/trace.go:171","msg":"trace[1683945349] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"101.873668ms","start":"2026-02-25T02:07:30.10833Z","end":"2026-02-25T02:07:30.210203Z","steps":["trace[1683945349] 'process raft request' (duration: 93.790824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:35.914127Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602844015896679,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:07:36.089664Z","caller":"traceutil/trace.go:171","msg":"trace[168840388] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"676.57481ms","start":"2026-02-25T02:07:35.413065Z","end":"2026-02-25T02:07:36.089639Z","steps":["trace[168840388] 'read index received' (duration: 676.408727ms)","trace[168840388] 'applied index is now lower than readState.Index' (duration: 164.313µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:36.089725Z","caller":"traceutil/trace.go:171","msg":"trace[2141289045] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"835.647817ms","start":"2026-02-25T02:07:35.25405Z","end":"2026-02-25T02:07:36.089698Z","steps":["trace[2141289045] 'process raft request' (duration: 835.445023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:36.089836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.758535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T02:07:36.089849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.25402Z","time spent":"835.76609ms","remote":"127.0.0.1:33054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:36.089842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.014598ms","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-25T02:07:36.089925Z","caller":"traceutil/trace.go:171","msg":"trace[905846662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:373; }","duration":"675.11597ms","start":"2026-02-25T02:07:35.414792Z","end":"2026-02-25T02:07:36.089908Z","steps":["trace[905846662] 'agreement among raft nodes before linearized reading' (duration: 674.995587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:36.089985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.414786Z","time spent":"675.184501ms","remote":"127.0.0.1:32892","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-25T02:07:36.089877Z","caller":"traceutil/trace.go:171","msg":"trace[2034753373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"676.845446ms","start":"2026-02-25T02:07:35.413021Z","end":"2026-02-25T02:07:36.089867Z","steps":["trace[2034753373] 'agreement among raft nodes before linearized reading' (duration: 676.755104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:36.090072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.412987Z","time spent":"677.075871ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:07:36.899246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.846116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:36.89936Z","caller":"traceutil/trace.go:171","msg":"trace[1362059120] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"804.371216ms","start":"2026-02-25T02:07:36.094971Z","end":"2026-02-25T02:07:36.899342Z","steps":["trace[1362059120] 'process raft request' (duration: 452.348636ms)","trace[1362059120] 'compare' (duration: 351.728944ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:36.899421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.094938Z","time spent":"804.457078ms","remote":"127.0.0.1:32892","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-25T02:07:37.075739Z","caller":"traceutil/trace.go:171","msg":"trace[1573232320] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"980.308155ms","start":"2026-02-25T02:07:36.095395Z","end":"2026-02-25T02:07:37.075703Z","steps":["trace[1573232320] 'process raft request' (duration: 938.6394ms)","trace[1573232320] 'compare' (duration: 41.396719ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:37.075912Z","caller":"traceutil/trace.go:171","msg":"trace[1623365489] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"979.502296ms","start":"2026-02-25T02:07:36.096373Z","end":"2026-02-25T02:07:37.075876Z","steps":["trace[1623365489] 'process raft request' (duration: 979.18644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.077373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.096346Z","time spent":"980.963219ms","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:07:37.077547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.095366Z","time spent":"980.466618ms","remote":"127.0.0.1:32892","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-25T02:07:37.190293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.469309ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T02:07:37.190559Z","caller":"traceutil/trace.go:171","msg":"trace[1937187533] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"108.916219ms","start":"2026-02-25T02:07:37.081625Z","end":"2026-02-25T02:07:37.190541Z","steps":["trace[1937187533] 'process raft request' (duration: 108.805567ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.190708Z","caller":"traceutil/trace.go:171","msg":"trace[1972647492] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:389; }","duration":"1.071511264s","start":"2026-02-25T02:07:36.119057Z","end":"2026-02-25T02:07:37.190568Z","steps":["trace[1972647492] 'read index received' (duration: 428.272569ms)","trace[1972647492] 'applied index is now lower than readState.Index' (duration: 643.231525ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:37.190814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071759929s","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-25T02:07:37.190862Z","caller":"traceutil/trace.go:171","msg":"trace[1243903228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"1.071860691s","start":"2026-02-25T02:07:36.11899Z","end":"2026-02-25T02:07:37.19085Z","steps":["trace[1243903228] 'agreement among raft nodes before linearized reading' (duration: 1.071739648s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.190897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.118957Z","time spent":"1.071931052s","remote":"127.0.0.1:32892","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-25T02:07:37.201273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.077027ms","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-25T02:07:37.201299Z","caller":"traceutil/trace.go:171","msg":"trace[1493184307] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"118.026405ms","start":"2026-02-25T02:07:37.083249Z","end":"2026-02-25T02:07:37.201276Z","steps":["trace[1493184307] 'process raft request' (duration: 117.769019ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:37.201351Z","caller":"traceutil/trace.go:171","msg":"trace[428861003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:379; }","duration":"809.22875ms","start":"2026-02-25T02:07:36.392104Z","end":"2026-02-25T02:07:37.201333Z","steps":["trace[428861003] 'agreement among raft nodes before linearized reading' (duration: 809.067136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.201385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.392083Z","time spent":"809.294992ms","remote":"127.0.0.1:32892","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-25T02:07:37.201369Z","caller":"traceutil/trace.go:171","msg":"trace[1854413931] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"117.582225ms","start":"2026-02-25T02:07:37.083763Z","end":"2026-02-25T02:07:37.201345Z","steps":["trace[1854413931] 'process raft request' (duration: 117.35659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.201404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.439176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:37.201448Z","caller":"traceutil/trace.go:171","msg":"trace[988674474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"661.522068ms","start":"2026-02-25T02:07:36.539914Z","end":"2026-02-25T02:07:37.201436Z","steps":["trace[988674474] 'agreement among raft nodes before linearized reading' (duration: 661.440926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:37.201478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.539895Z","time spent":"661.575189ms","remote":"127.0.0.1:60844","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-25T02:07:50.690784Z","caller":"traceutil/trace.go:171","msg":"trace[254916942] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"148.786736ms","start":"2026-02-25T02:07:50.541971Z","end":"2026-02-25T02:07:50.690758Z","steps":["trace[254916942] 'read index received' (duration: 148.49927ms)","trace[254916942] 'applied index is now lower than readState.Index' (duration: 286.396µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:50.690911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.925128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:50.690898Z","caller":"traceutil/trace.go:171","msg":"trace[1078614578] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"500.887417ms","start":"2026-02-25T02:07:50.189975Z","end":"2026-02-25T02:07:50.690863Z","steps":["trace[1078614578] 'process raft request' (duration: 500.641482ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:50.690946Z","caller":"traceutil/trace.go:171","msg":"trace[284920060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"149.0152ms","start":"2026-02-25T02:07:50.54192Z","end":"2026-02-25T02:07:50.690936Z","steps":["trace[284920060] 'agreement among raft nodes before linearized reading' (duration: 148.938879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:50.691066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:50.189933Z","time spent":"501.04915ms","remote":"127.0.0.1:32804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4216,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:51.612947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.703399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:51.613058Z","caller":"traceutil/trace.go:171","msg":"trace[682027806] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"607.177141ms","start":"2026-02-25T02:07:51.005862Z","end":"2026-02-25T02:07:51.613039Z","steps":["trace[682027806] 'read index received' (duration: 454.225036ms)","trace[682027806] 'applied index is now lower than readState.Index' (duration: 152.950575ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:51.613121Z","caller":"traceutil/trace.go:171","msg":"trace[1414987489] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"849.098648ms","start":"2026-02-25T02:07:50.764009Z","end":"2026-02-25T02:07:51.613108Z","steps":["trace[1414987489] 'process raft request' (duration: 696.137922ms)","trace[1414987489] 'compare' (duration: 152.451134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:51.613168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.313474ms","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-25T02:07:51.613205Z","caller":"traceutil/trace.go:171","msg":"trace[430751888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"607.397895ms","start":"2026-02-25T02:07:51.005795Z","end":"2026-02-25T02:07:51.613193Z","steps":["trace[430751888] 'agreement among raft nodes before linearized reading' (duration: 607.330994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:51.61325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:51.005769Z","time spent":"607.472857ms","remote":"127.0.0.1:32892","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-25T02:07:51.614138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:50.763984Z","time spent":"849.881475ms","remote":"127.0.0.1:32892","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-25T02:07:51.614253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.894376ms","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-25T02:07:51.614441Z","caller":"traceutil/trace.go:171","msg":"trace[1764869528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"310.099101ms","start":"2026-02-25T02:07:51.304295Z","end":"2026-02-25T02:07:51.614394Z","steps":["trace[1764869528] 'agreement among raft nodes before linearized reading' (duration: 308.970597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:51.614582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:51.304285Z","time spent":"310.208033ms","remote":"127.0.0.1:32892","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-25T02:07:51.614206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.031266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T02:07:51.61333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.371255ms","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-25T02:07:51.61473Z","caller":"traceutil/trace.go:171","msg":"trace[632573846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"470.552337ms","start":"2026-02-25T02:07:51.14412Z","end":"2026-02-25T02:07:51.614672Z","steps":["trace[632573846] 'agreement among raft nodes before linearized reading' (duration: 469.085156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:51.614758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.056912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T02:07:51.614778Z","caller":"traceutil/trace.go:171","msg":"trace[328902608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:469; }","duration":"310.810956ms","start":"2026-02-25T02:07:51.303902Z","end":"2026-02-25T02:07:51.614713Z","steps":["trace[328902608] 'agreement among raft nodes before linearized reading' (duration: 309.371295ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:51.614839Z","caller":"traceutil/trace.go:171","msg":"trace[348962387] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:469; }","duration":"461.546384ms","start":"2026-02-25T02:07:51.153268Z","end":"2026-02-25T02:07:51.614815Z","steps":["trace[348962387] 'agreement among raft nodes before linearized reading' (duration: 460.019771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:51.614879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:51.144096Z","time spent":"470.664669ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:07:51.614913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:51.153253Z","time spent":"461.612895ms","remote":"127.0.0.1:60888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-02-25T02:07:51.614893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:51.303876Z","time spent":"310.974669ms","remote":"127.0.0.1:32892","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-25T02:07:58.517088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.027338ms","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-25T02:07:58.517094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.287391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w7nxl\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T02:07:58.517192Z","caller":"traceutil/trace.go:171","msg":"trace[606780537] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w7nxl; range_end:; response_count:1; response_revision:508; }","duration":"667.434505ms","start":"2026-02-25T02:07:57.849738Z","end":"2026-02-25T02:07:58.517173Z","steps":["trace[606780537] 'range keys from in-memory index tree' (duration: 667.117228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:58.517262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:57.849722Z","time spent":"667.529366ms","remote":"127.0.0.1:32806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-w7nxl\" "} {"level":"warn","ts":"2026-02-25T02:07:58.517265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.545641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:58.517242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.896585ms","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-25T02:07:58.517359Z","caller":"traceutil/trace.go:171","msg":"trace[1659126471] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:508; }","duration":"859.673614ms","start":"2026-02-25T02:07:57.657668Z","end":"2026-02-25T02:07:58.517341Z","steps":["trace[1659126471] 'range keys from in-memory index tree' (duration: 859.424979ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:58.51719Z","caller":"traceutil/trace.go:171","msg":"trace[2058767200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"797.14797ms","start":"2026-02-25T02:07:57.72001Z","end":"2026-02-25T02:07:58.517158Z","steps":["trace[2058767200] 'range keys from in-memory index tree' (duration: 796.866914ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:58.517401Z","caller":"traceutil/trace.go:171","msg":"trace[793890385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"797.1193ms","start":"2026-02-25T02:07:57.720258Z","end":"2026-02-25T02:07:58.517377Z","steps":["trace[793890385] 'range keys from in-memory index tree' (duration: 796.67122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:58.517253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.664632ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:58.517455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:57.720126Z","time spent":"797.317913ms","remote":"127.0.0.1:32892","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-25T02:07:58.517461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:57.720003Z","time spent":"797.429706ms","remote":"127.0.0.1:32892","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-25T02:07:58.517485Z","caller":"traceutil/trace.go:171","msg":"trace[1634220211] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:508; }","duration":"486.921158ms","start":"2026-02-25T02:07:58.030539Z","end":"2026-02-25T02:07:58.51746Z","steps":["trace[1634220211] 'range keys from in-memory index tree' (duration: 486.630052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:58.517491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.811619ms","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-25T02:07:58.517529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.359397ms","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-25T02:07:58.517545Z","caller":"traceutil/trace.go:171","msg":"trace[1898489982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"841.895031ms","start":"2026-02-25T02:07:57.675635Z","end":"2026-02-25T02:07:58.51753Z","steps":["trace[1898489982] 'range keys from in-memory index tree' (duration: 841.717967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:58.517586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:57.675615Z","time spent":"841.961923ms","remote":"127.0.0.1:32892","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-25T02:07:58.517419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:57.657645Z","time spent":"859.753845ms","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-02-25T02:07:58.517583Z","caller":"traceutil/trace.go:171","msg":"trace[300041501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"798.442568ms","start":"2026-02-25T02:07:57.719127Z","end":"2026-02-25T02:07:58.51757Z","steps":["trace[300041501] 'range keys from in-memory index tree' (duration: 798.257805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:58.517629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:57.719109Z","time spent":"798.50854ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:07:59.041125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602844015896947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:07:59.541628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602844015896947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:00.042756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602844015896947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:00.540017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999747189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:08:00.540152Z","caller":"traceutil/trace.go:171","msg":"trace[769417563] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999929663s","start":"2026-02-25T02:07:58.540191Z","end":"2026-02-25T02:08:00.54012Z","steps":["trace[769417563] 'agreement among raft nodes before linearized reading' (duration: 1.99978517s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:00.540204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:58.540158Z","time spent":"2.000035426s","remote":"127.0.0.1:60844","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 02:08:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:08:00.543347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602844015896947,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:00.648347Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.947367665s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:08:00.648842Z","caller":"traceutil/trace.go:171","msg":"trace[1827705492] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"2.126494252s","start":"2026-02-25T02:07:58.522295Z","end":"2026-02-25T02:08:00.648789Z","steps":["trace[1827705492] 'process raft request' (duration: 2.126338618s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:00.64901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:58.522228Z","time spent":"2.126696826s","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:01.581813Z","caller":"traceutil/trace.go:171","msg":"trace[879421211] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:529; }","duration":"3.041532855s","start":"2026-02-25T02:07:58.540257Z","end":"2026-02-25T02:08:01.581789Z","steps":["trace[879421211] 'read index received' (duration: 2.108377173s)","trace[879421211] 'applied index is now lower than readState.Index' (duration: 933.154912ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:01.582148Z","caller":"traceutil/trace.go:171","msg":"trace[1461487346] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"3.059727296s","start":"2026-02-25T02:07:58.522409Z","end":"2026-02-25T02:08:01.582136Z","steps":["trace[1461487346] 'process raft request' (duration: 3.059043721s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:01.582288Z","caller":"traceutil/trace.go:171","msg":"trace[133566472] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"3.050274543s","start":"2026-02-25T02:07:58.531933Z","end":"2026-02-25T02:08:01.582208Z","steps":["trace[133566472] 'process raft request' (duration: 3.049762962s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:01.582386Z","caller":"traceutil/trace.go:171","msg":"trace[1384777982] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"3.059708105s","start":"2026-02-25T02:07:58.522539Z","end":"2026-02-25T02:08:01.582247Z","steps":["trace[1384777982] 'process raft request' (duration: 3.059100822s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:01.582424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.78669298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:01.582473Z","caller":"traceutil/trace.go:171","msg":"trace[1941280575] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:513; }","duration":"2.786783553s","start":"2026-02-25T02:07:58.795675Z","end":"2026-02-25T02:08:01.582459Z","steps":["trace[1941280575] 'agreement among raft nodes before linearized reading' (duration: 2.786705082s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:01.582479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:58.531917Z","time spent":"3.050468617s","remote":"127.0.0.1:32806","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-25T02:08:01.582502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:58.522523Z","time spent":"3.05991528s","remote":"127.0.0.1:32892","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-25T02:08:01.582312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:58.522374Z","time spent":"3.059810108s","remote":"127.0.0.1:32892","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-25T02:08:01.582514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:58.795638Z","time spent":"2.786865315s","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T02:08:01.587866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.94742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T02:08:01.582537Z","caller":"traceutil/trace.go:171","msg":"trace[573045534] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"3.060027212s","start":"2026-02-25T02:07:58.522456Z","end":"2026-02-25T02:08:01.582483Z","steps":["trace[573045534] 'process raft request' (duration: 3.059141433s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:01.587901Z","caller":"traceutil/trace.go:171","msg":"trace[562794788] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:513; }","duration":"434.031712ms","start":"2026-02-25T02:08:01.153861Z","end":"2026-02-25T02:08:01.587893Z","steps":["trace[562794788] 'agreement among raft nodes before linearized reading' (duration: 428.677447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:01.58789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047550019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:08:01.587925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:01.153826Z","time spent":"434.094734ms","remote":"127.0.0.1:60888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"info","ts":"2026-02-25T02:08:01.587949Z","caller":"traceutil/trace.go:171","msg":"trace[1302211438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"1.047644291s","start":"2026-02-25T02:08:00.540287Z","end":"2026-02-25T02:08:01.587931Z","steps":["trace[1302211438] 'agreement among raft nodes before linearized reading' (duration: 1.042422619s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:01.587994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:00.540265Z","time spent":"1.047717963s","remote":"127.0.0.1:60858","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-25T02:08:01.587999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:58.522422Z","time spent":"3.06550343s","remote":"127.0.0.1:32892","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-25T02:08:01.94117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.172736ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-25T02:08:01.941332Z","caller":"traceutil/trace.go:171","msg":"trace[28396581] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"347.204367ms","start":"2026-02-25T02:08:01.594114Z","end":"2026-02-25T02:08:01.941318Z","steps":["trace[28396581] 'read index received' (duration: 134.866368ms)","trace[28396581] 'applied index is now lower than readState.Index' (duration: 212.337319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:01.941406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:01.588915Z","time spent":"352.488349ms","remote":"127.0.0.1:60888","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T02:08:01.941546Z","caller":"traceutil/trace.go:171","msg":"trace[545311636] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"349.71251ms","start":"2026-02-25T02:08:01.591825Z","end":"2026-02-25T02:08:01.941538Z","steps":["trace[545311636] 'process raft request' (duration: 349.424514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:01.941608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:01.591804Z","time spent":"349.767191ms","remote":"127.0.0.1:60922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:08:01.941739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.624037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:01.941765Z","caller":"traceutil/trace.go:171","msg":"trace[847665826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"347.675108ms","start":"2026-02-25T02:08:01.594083Z","end":"2026-02-25T02:08:01.941758Z","steps":["trace[847665826] 'agreement among raft nodes before linearized reading' (duration: 347.613326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:01.941784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:01.594073Z","time spent":"347.706458ms","remote":"127.0.0.1:60844","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-25T02:08:01.941896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.927041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:01.941917Z","caller":"traceutil/trace.go:171","msg":"trace[1061349450] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:514; }","duration":"346.958772ms","start":"2026-02-25T02:08:01.594953Z","end":"2026-02-25T02:08:01.941912Z","steps":["trace[1061349450] 'agreement among raft nodes before linearized reading' (duration: 346.926261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:01.941935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:01.594948Z","time spent":"346.983102ms","remote":"127.0.0.1:60940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T02:08:03.154184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602844015896961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:03.655464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967602844015896961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:03.981876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.036105061s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:08:04.001024Z","caller":"traceutil/trace.go:171","msg":"trace[634673400] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"2.055324634s","start":"2026-02-25T02:08:01.945666Z","end":"2026-02-25T02:08:04.000991Z","steps":["trace[634673400] 'process raft request' (duration: 2.036501989s)","trace[634673400] 'compare' (duration: 18.679062ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:04.001206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:01.945649Z","time spent":"2.055453546s","remote":"127.0.0.1:60888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:04.059629Z","caller":"traceutil/trace.go:171","msg":"trace[111727625] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"2.113275128s","start":"2026-02-25T02:08:01.946284Z","end":"2026-02-25T02:08:04.059559Z","steps":["trace[111727625] 'process raft request' (duration: 2.113003481s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:04.059689Z","caller":"traceutil/trace.go:171","msg":"trace[263244428] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"2.07747069s","start":"2026-02-25T02:08:01.982178Z","end":"2026-02-25T02:08:04.059649Z","steps":["trace[263244428] 'process raft request' (duration: 2.077258024s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:04.05973Z","caller":"traceutil/trace.go:171","msg":"trace[81702324] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"427.280048ms","start":"2026-02-25T02:08:03.632413Z","end":"2026-02-25T02:08:04.059693Z","steps":["trace[81702324] 'process raft request' (duration: 427.106494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.059817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:01.94625Z","time spent":"2.113465342s","remote":"127.0.0.1:60922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:08:04.059845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.406637342s","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-25T02:08:04.059644Z","caller":"traceutil/trace.go:171","msg":"trace[1652752157] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:536; }","duration":"1.406383036s","start":"2026-02-25T02:08:02.653198Z","end":"2026-02-25T02:08:04.059581Z","steps":["trace[1652752157] 'read index received' (duration: 1.329011074s)","trace[1652752157] 'applied index is now lower than readState.Index' (duration: 77.370712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:04.060453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:03.632386Z","time spent":"427.444382ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:04.060579Z","caller":"traceutil/trace.go:171","msg":"trace[1725776647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"1.407379548s","start":"2026-02-25T02:08:02.653145Z","end":"2026-02-25T02:08:04.060525Z","steps":["trace[1725776647] 'agreement among raft nodes before linearized reading' (duration: 1.40653875s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.060875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:02.653122Z","time spent":"1.407729045s","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:08:04.060989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.224178ms","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-25T02:08:04.061081Z","caller":"traceutil/trace.go:171","msg":"trace[971120002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"469.31612ms","start":"2026-02-25T02:08:03.59172Z","end":"2026-02-25T02:08:04.061036Z","steps":["trace[971120002] 'agreement among raft nodes before linearized reading' (duration: 469.177707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.061082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.815246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:08:04.061122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:03.591703Z","time spent":"469.410162ms","remote":"127.0.0.1:32892","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-25T02:08:04.060337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:01.982155Z","time spent":"2.077618892s","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:04.061176Z","caller":"traceutil/trace.go:171","msg":"trace[1766695189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"521.005861ms","start":"2026-02-25T02:08:03.54015Z","end":"2026-02-25T02:08:04.061156Z","steps":["trace[1766695189] 'agreement among raft nodes before linearized reading' (duration: 520.536621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.061267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:03.540131Z","time spent":"521.121813ms","remote":"127.0.0.1:60844","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-25T02:08:04.062614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.026028ms","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-25T02:08:04.062727Z","caller":"traceutil/trace.go:171","msg":"trace[1076461556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"471.14309ms","start":"2026-02-25T02:08:03.59155Z","end":"2026-02-25T02:08:04.062693Z","steps":["trace[1076461556] 'agreement among raft nodes before linearized reading' (duration: 469.897743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.062742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.334534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T02:08:04.062801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:03.591542Z","time spent":"471.226992ms","remote":"127.0.0.1:32892","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-25T02:08:04.062884Z","caller":"traceutil/trace.go:171","msg":"trace[877222605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"471.541798ms","start":"2026-02-25T02:08:03.591299Z","end":"2026-02-25T02:08:04.062841Z","steps":["trace[877222605] 'agreement among raft nodes before linearized reading' (duration: 470.25328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:04.062969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:03.591278Z","time spent":"471.676221ms","remote":"127.0.0.1:32892","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-25T02:08:05.108087Z","caller":"traceutil/trace.go:171","msg":"trace[1361874505] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"124.527485ms","start":"2026-02-25T02:08:04.983478Z","end":"2026-02-25T02:08:05.108006Z","steps":["trace[1361874505] 'process raft request' (duration: 124.384502ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.109142Z","caller":"traceutil/trace.go:171","msg":"trace[1777708665] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"126.595128ms","start":"2026-02-25T02:08:04.981483Z","end":"2026-02-25T02:08:05.108078Z","steps":["trace[1777708665] 'process raft request' (duration: 60.039718ms)","trace[1777708665] 'compare' (duration: 66.045399ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:05.387639Z","caller":"traceutil/trace.go:171","msg":"trace[1298128871] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"267.746011ms","start":"2026-02-25T02:08:05.119864Z","end":"2026-02-25T02:08:05.38761Z","steps":["trace[1298128871] 'process raft request' (duration: 267.675209ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.387828Z","caller":"traceutil/trace.go:171","msg":"trace[1622717756] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"267.974066ms","start":"2026-02-25T02:08:05.119823Z","end":"2026-02-25T02:08:05.387797Z","steps":["trace[1622717756] 'process raft request' (duration: 267.641189ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.387889Z","caller":"traceutil/trace.go:171","msg":"trace[1438726553] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"268.475886ms","start":"2026-02-25T02:08:05.119388Z","end":"2026-02-25T02:08:05.387864Z","steps":["trace[1438726553] 'process raft request' (duration: 170.976152ms)","trace[1438726553] 'compare' (duration: 96.939211ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:05.388056Z","caller":"traceutil/trace.go:171","msg":"trace[1733703398] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"268.092408ms","start":"2026-02-25T02:08:05.119947Z","end":"2026-02-25T02:08:05.38804Z","steps":["trace[1733703398] 'process raft request' (duration: 267.622018ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.388376Z","caller":"traceutil/trace.go:171","msg":"trace[150631650] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"268.214521ms","start":"2026-02-25T02:08:05.120143Z","end":"2026-02-25T02:08:05.388357Z","steps":["trace[150631650] 'process raft request' (duration: 268.062338ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.388368Z","caller":"traceutil/trace.go:171","msg":"trace[1140519145] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"268.20678ms","start":"2026-02-25T02:08:05.120131Z","end":"2026-02-25T02:08:05.388338Z","steps":["trace[1140519145] 'process raft request' (duration: 267.828562ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.3884Z","caller":"traceutil/trace.go:171","msg":"trace[1267103231] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"268.18145ms","start":"2026-02-25T02:08:05.120192Z","end":"2026-02-25T02:08:05.388373Z","steps":["trace[1267103231] 'process raft request' (duration: 268.115049ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.388495Z","caller":"traceutil/trace.go:171","msg":"trace[1917245674] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"268.226181ms","start":"2026-02-25T02:08:05.120256Z","end":"2026-02-25T02:08:05.388482Z","steps":["trace[1917245674] 'process raft request' (duration: 268.108498ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.388518Z","caller":"traceutil/trace.go:171","msg":"trace[1376612832] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"268.285032ms","start":"2026-02-25T02:08:05.120221Z","end":"2026-02-25T02:08:05.388506Z","steps":["trace[1376612832] 'process raft request' (duration: 268.114368ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.388559Z","caller":"traceutil/trace.go:171","msg":"trace[1960566837] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"268.252242ms","start":"2026-02-25T02:08:05.120297Z","end":"2026-02-25T02:08:05.388549Z","steps":["trace[1960566837] 'process raft request' (duration: 268.117159ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.38861Z","caller":"traceutil/trace.go:171","msg":"trace[675241666] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"267.325111ms","start":"2026-02-25T02:08:05.121276Z","end":"2026-02-25T02:08:05.388601Z","steps":["trace[675241666] 'process raft request' (duration: 267.201928ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.388617Z","caller":"traceutil/trace.go:171","msg":"trace[1026837870] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"266.530894ms","start":"2026-02-25T02:08:05.122075Z","end":"2026-02-25T02:08:05.388606Z","steps":["trace[1026837870] 'process raft request' (duration: 266.458033ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.388632Z","caller":"traceutil/trace.go:171","msg":"trace[540335860] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"268.396445ms","start":"2026-02-25T02:08:05.120229Z","end":"2026-02-25T02:08:05.388625Z","steps":["trace[540335860] 'process raft request' (duration: 268.16052ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.388707Z","caller":"traceutil/trace.go:171","msg":"trace[716825980] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"268.357524ms","start":"2026-02-25T02:08:05.120339Z","end":"2026-02-25T02:08:05.388696Z","steps":["trace[716825980] 'process raft request' (duration: 268.109249ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.388751Z","caller":"traceutil/trace.go:171","msg":"trace[969366209] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"266.517844ms","start":"2026-02-25T02:08:05.122225Z","end":"2026-02-25T02:08:05.388743Z","steps":["trace[969366209] 'process raft request' (duration: 266.34898ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.55286Z","caller":"traceutil/trace.go:171","msg":"trace[1344785821] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"125.898165ms","start":"2026-02-25T02:08:05.426933Z","end":"2026-02-25T02:08:05.552831Z","steps":["trace[1344785821] 'process raft request' (duration: 125.619838ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.55304Z","caller":"traceutil/trace.go:171","msg":"trace[1765692344] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"120.561109ms","start":"2026-02-25T02:08:05.432471Z","end":"2026-02-25T02:08:05.553032Z","steps":["trace[1765692344] 'process raft request' (duration: 120.305404ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.553095Z","caller":"traceutil/trace.go:171","msg":"trace[400308601] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"126.109378ms","start":"2026-02-25T02:08:05.426935Z","end":"2026-02-25T02:08:05.553045Z","steps":["trace[400308601] 'process raft request' (duration: 125.780621ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.553221Z","caller":"traceutil/trace.go:171","msg":"trace[1495541458] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"113.58641ms","start":"2026-02-25T02:08:05.43958Z","end":"2026-02-25T02:08:05.553167Z","steps":["trace[1495541458] 'process raft request' (duration: 113.312364ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:05.844907Z","caller":"traceutil/trace.go:171","msg":"trace[2125983943] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"150.82347ms","start":"2026-02-25T02:08:05.694057Z","end":"2026-02-25T02:08:05.844881Z","steps":["trace[2125983943] 'process raft request' (duration: 103.142336ms)","trace[2125983943] 'compare' (duration: 47.398048ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:05.845248Z","caller":"traceutil/trace.go:171","msg":"trace[365507836] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"143.257947ms","start":"2026-02-25T02:08:05.701979Z","end":"2026-02-25T02:08:05.845237Z","steps":["trace[365507836] 'process raft request' (duration: 142.821487ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.064776Z","caller":"traceutil/trace.go:171","msg":"trace[608230593] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"186.097877ms","start":"2026-02-25T02:08:05.878648Z","end":"2026-02-25T02:08:06.064746Z","steps":["trace[608230593] 'process raft request' (duration: 185.997465ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.064797Z","caller":"traceutil/trace.go:171","msg":"trace[282603292] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"186.855282ms","start":"2026-02-25T02:08:05.877918Z","end":"2026-02-25T02:08:06.064773Z","steps":["trace[282603292] 'process raft request' (duration: 186.561406ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.065751Z","caller":"traceutil/trace.go:171","msg":"trace[646820331] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"185.416374ms","start":"2026-02-25T02:08:05.880316Z","end":"2026-02-25T02:08:06.065732Z","steps":["trace[646820331] 'process raft request' (duration: 184.395111ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.095477Z","caller":"traceutil/trace.go:171","msg":"trace[681359883] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"199.581356ms","start":"2026-02-25T02:08:05.895863Z","end":"2026-02-25T02:08:06.095444Z","steps":["trace[681359883] 'process raft request' (duration: 199.377362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.095582Z","caller":"traceutil/trace.go:171","msg":"trace[691325055] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"199.639268ms","start":"2026-02-25T02:08:05.895881Z","end":"2026-02-25T02:08:06.09552Z","steps":["trace[691325055] 'process raft request' (duration: 199.474594ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.095641Z","caller":"traceutil/trace.go:171","msg":"trace[404410327] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"199.680139ms","start":"2026-02-25T02:08:05.895941Z","end":"2026-02-25T02:08:06.095621Z","steps":["trace[404410327] 'process raft request' (duration: 199.447054ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.095909Z","caller":"traceutil/trace.go:171","msg":"trace[2042711112] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:625; }","duration":"197.378079ms","start":"2026-02-25T02:08:05.898039Z","end":"2026-02-25T02:08:06.095417Z","steps":["trace[2042711112] 'read index received' (duration: 166.579537ms)","trace[2042711112] 'applied index is now lower than readState.Index' (duration: 30.797552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:06.095986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.91742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:3494"} {"level":"info","ts":"2026-02-25T02:08:06.096075Z","caller":"traceutil/trace.go:171","msg":"trace[1816278229] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:609; }","duration":"198.051233ms","start":"2026-02-25T02:08:05.898004Z","end":"2026-02-25T02:08:06.096055Z","steps":["trace[1816278229] 'agreement among raft nodes before linearized reading' (duration: 197.756537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:06.098706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.093128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4330"} {"level":"info","ts":"2026-02-25T02:08:06.098768Z","caller":"traceutil/trace.go:171","msg":"trace[634941139] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:610; }","duration":"200.188699ms","start":"2026-02-25T02:08:05.898562Z","end":"2026-02-25T02:08:06.098751Z","steps":["trace[634941139] 'agreement among raft nodes before linearized reading' (duration: 199.979365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:06.098992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.380433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:06.099049Z","caller":"traceutil/trace.go:171","msg":"trace[2007299964] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-startupapicheck; range_end:; response_count:0; response_revision:610; }","duration":"199.464435ms","start":"2026-02-25T02:08:05.899571Z","end":"2026-02-25T02:08:06.099036Z","steps":["trace[2007299964] 'agreement among raft nodes before linearized reading' (duration: 199.386543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:06.09917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.508237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4618"} {"level":"info","ts":"2026-02-25T02:08:06.099228Z","caller":"traceutil/trace.go:171","msg":"trace[481427152] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:610; }","duration":"200.586819ms","start":"2026-02-25T02:08:05.898627Z","end":"2026-02-25T02:08:06.099214Z","steps":["trace[481427152] 'agreement among raft nodes before linearized reading' (duration: 200.437886ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.204144Z","caller":"traceutil/trace.go:171","msg":"trace[293575084] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"103.896132ms","start":"2026-02-25T02:08:06.10022Z","end":"2026-02-25T02:08:06.204116Z","steps":["trace[293575084] 'process raft request' (duration: 41.41526ms)","trace[293575084] 'compare' (duration: 62.302668ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:06.205286Z","caller":"traceutil/trace.go:171","msg":"trace[1822847889] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"102.357378ms","start":"2026-02-25T02:08:06.102914Z","end":"2026-02-25T02:08:06.205272Z","steps":["trace[1822847889] 'process raft request' (duration: 102.279436ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.205295Z","caller":"traceutil/trace.go:171","msg":"trace[2113819536] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"104.084846ms","start":"2026-02-25T02:08:06.101184Z","end":"2026-02-25T02:08:06.205269Z","steps":["trace[2113819536] 'process raft request' (duration: 103.449702ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.205332Z","caller":"traceutil/trace.go:171","msg":"trace[692083681] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"103.269277ms","start":"2026-02-25T02:08:06.102024Z","end":"2026-02-25T02:08:06.205293Z","steps":["trace[692083681] 'process raft request' (duration: 103.144985ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.205484Z","caller":"traceutil/trace.go:171","msg":"trace[1004438507] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"103.818511ms","start":"2026-02-25T02:08:06.101658Z","end":"2026-02-25T02:08:06.205477Z","steps":["trace[1004438507] 'process raft request' (duration: 103.458383ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.205713Z","caller":"traceutil/trace.go:171","msg":"trace[1497523350] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"101.91394ms","start":"2026-02-25T02:08:06.103779Z","end":"2026-02-25T02:08:06.205693Z","steps":["trace[1497523350] 'process raft request' (duration: 101.462359ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.205915Z","caller":"traceutil/trace.go:171","msg":"trace[1842030802] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"100.006528ms","start":"2026-02-25T02:08:06.105893Z","end":"2026-02-25T02:08:06.2059Z","steps":["trace[1842030802] 'process raft request' (duration: 99.773453ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.206056Z","caller":"traceutil/trace.go:171","msg":"trace[1218520284] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"100.172422ms","start":"2026-02-25T02:08:06.105868Z","end":"2026-02-25T02:08:06.206041Z","steps":["trace[1218520284] 'process raft request' (duration: 99.978378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:06.245741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.802985ms","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-25T02:08:06.245808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.865287ms","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-25T02:08:06.245823Z","caller":"traceutil/trace.go:171","msg":"trace[1804993149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"127.914388ms","start":"2026-02-25T02:08:06.117891Z","end":"2026-02-25T02:08:06.245805Z","steps":["trace[1804993149] 'agreement among raft nodes before linearized reading' (duration: 127.685783ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:06.245874Z","caller":"traceutil/trace.go:171","msg":"trace[1456350104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"127.993859ms","start":"2026-02-25T02:08:06.117864Z","end":"2026-02-25T02:08:06.245858Z","steps":["trace[1456350104] 'agreement among raft nodes before linearized reading' (duration: 127.771254ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:07.115501Z","caller":"traceutil/trace.go:171","msg":"trace[624430982] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"110.545544ms","start":"2026-02-25T02:08:07.004922Z","end":"2026-02-25T02:08:07.115468Z","steps":["trace[624430982] 'process raft request' (duration: 64.920514ms)","trace[624430982] 'compare' (duration: 45.339724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:07.931448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.929083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:08:07.93144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.823195ms","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-25T02:08:07.931535Z","caller":"traceutil/trace.go:171","msg":"trace[1157829089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"389.077116ms","start":"2026-02-25T02:08:07.54244Z","end":"2026-02-25T02:08:07.931518Z","steps":["trace[1157829089] 'range keys from in-memory index tree' (duration: 388.802151ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:07.931545Z","caller":"traceutil/trace.go:171","msg":"trace[442067257] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:654; }","duration":"126.970628ms","start":"2026-02-25T02:08:07.804555Z","end":"2026-02-25T02:08:07.931526Z","steps":["trace[442067257] 'range keys from in-memory index tree' (duration: 126.683142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:07.931575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:07.542413Z","time spent":"389.154618ms","remote":"127.0.0.1:60844","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-25T02:08:08.255337Z","caller":"traceutil/trace.go:171","msg":"trace[592442825] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:680; }","duration":"189.289765ms","start":"2026-02-25T02:08:08.066029Z","end":"2026-02-25T02:08:08.255319Z","steps":["trace[592442825] 'read index received' (duration: 132.135988ms)","trace[592442825] 'applied index is now lower than readState.Index' (duration: 57.152947ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:08.255422Z","caller":"traceutil/trace.go:171","msg":"trace[1355950017] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"206.195968ms","start":"2026-02-25T02:08:08.04922Z","end":"2026-02-25T02:08:08.255416Z","steps":["trace[1355950017] 'process raft request' (duration: 206.064695ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.255383Z","caller":"traceutil/trace.go:171","msg":"trace[320272999] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"257.182894ms","start":"2026-02-25T02:08:07.998177Z","end":"2026-02-25T02:08:08.25536Z","steps":["trace[320272999] 'process raft request' (duration: 257.089512ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.255382Z","caller":"traceutil/trace.go:171","msg":"trace[643672879] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"258.94417ms","start":"2026-02-25T02:08:07.996406Z","end":"2026-02-25T02:08:08.25535Z","steps":["trace[643672879] 'process raft request' (duration: 201.773673ms)","trace[643672879] 'compare' (duration: 56.937143ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:08.255507Z","caller":"traceutil/trace.go:171","msg":"trace[810331425] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"258.798078ms","start":"2026-02-25T02:08:07.996657Z","end":"2026-02-25T02:08:08.255455Z","steps":["trace[810331425] 'process raft request' (duration: 258.585553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.255628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.063025ms","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":"info","ts":"2026-02-25T02:08:08.255678Z","caller":"traceutil/trace.go:171","msg":"trace[233028964] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:661; }","duration":"260.132916ms","start":"2026-02-25T02:08:07.995529Z","end":"2026-02-25T02:08:08.255662Z","steps":["trace[233028964] 'agreement among raft nodes before linearized reading' (duration: 260.008093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.255676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.099566ms","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":"warn","ts":"2026-02-25T02:08:08.255729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.591468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w7nxl\" ","response":"range_response_count:1 size:17167"} {"level":"info","ts":"2026-02-25T02:08:08.255739Z","caller":"traceutil/trace.go:171","msg":"trace[1546561747] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:661; }","duration":"260.218987ms","start":"2026-02-25T02:08:07.995505Z","end":"2026-02-25T02:08:08.255724Z","steps":["trace[1546561747] 'agreement among raft nodes before linearized reading' (duration: 260.029023ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.255771Z","caller":"traceutil/trace.go:171","msg":"trace[946837039] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w7nxl; range_end:; response_count:1; response_revision:661; }","duration":"207.68928ms","start":"2026-02-25T02:08:08.04807Z","end":"2026-02-25T02:08:08.255759Z","steps":["trace[946837039] 'agreement among raft nodes before linearized reading' (duration: 207.534657ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.307811Z","caller":"traceutil/trace.go:171","msg":"trace[278499911] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"114.182733ms","start":"2026-02-25T02:08:08.193601Z","end":"2026-02-25T02:08:08.307784Z","steps":["trace[278499911] 'process raft request' (duration: 114.075671ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.387447Z","caller":"traceutil/trace.go:171","msg":"trace[1328962465] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"128.240564ms","start":"2026-02-25T02:08:08.259175Z","end":"2026-02-25T02:08:08.387416Z","steps":["trace[1328962465] 'process raft request' (duration: 127.705583ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.391342Z","caller":"traceutil/trace.go:171","msg":"trace[184258393] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"131.632647ms","start":"2026-02-25T02:08:08.259251Z","end":"2026-02-25T02:08:08.390883Z","steps":["trace[184258393] 'process raft request' (duration: 127.848675ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.391642Z","caller":"traceutil/trace.go:171","msg":"trace[333756706] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"122.637264ms","start":"2026-02-25T02:08:08.268976Z","end":"2026-02-25T02:08:08.391613Z","steps":["trace[333756706] 'process raft request' (duration: 122.526942ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.391666Z","caller":"traceutil/trace.go:171","msg":"trace[1639438200] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"130.83945ms","start":"2026-02-25T02:08:08.260803Z","end":"2026-02-25T02:08:08.391643Z","steps":["trace[1639438200] 'process raft request' (duration: 129.977492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.391697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.321758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T02:08:08.39172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.746643ms","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-25T02:08:08.391734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.815635ms","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-25T02:08:08.391757Z","caller":"traceutil/trace.go:171","msg":"trace[1826193946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:666; }","duration":"122.424149ms","start":"2026-02-25T02:08:08.269317Z","end":"2026-02-25T02:08:08.391741Z","steps":["trace[1826193946] 'agreement among raft nodes before linearized reading' (duration: 122.269606ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.391767Z","caller":"traceutil/trace.go:171","msg":"trace[1180328313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:666; }","duration":"120.882885ms","start":"2026-02-25T02:08:08.270874Z","end":"2026-02-25T02:08:08.391757Z","steps":["trace[1180328313] 'agreement among raft nodes before linearized reading' (duration: 120.786883ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.391761Z","caller":"traceutil/trace.go:171","msg":"trace[2064854052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"120.816684ms","start":"2026-02-25T02:08:08.270933Z","end":"2026-02-25T02:08:08.39175Z","steps":["trace[2064854052] 'agreement among raft nodes before linearized reading' (duration: 120.679521ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.485406Z","caller":"traceutil/trace.go:171","msg":"trace[528906078] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"173.998517ms","start":"2026-02-25T02:08:08.311378Z","end":"2026-02-25T02:08:08.485377Z","steps":["trace[528906078] 'process raft request' (duration: 173.821523ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.532267Z","caller":"traceutil/trace.go:171","msg":"trace[662210776] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"137.429102ms","start":"2026-02-25T02:08:08.394813Z","end":"2026-02-25T02:08:08.532242Z","steps":["trace[662210776] 'process raft request' (duration: 136.624815ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.532482Z","caller":"traceutil/trace.go:171","msg":"trace[1340203883] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"136.691486ms","start":"2026-02-25T02:08:08.395742Z","end":"2026-02-25T02:08:08.532433Z","steps":["trace[1340203883] 'process raft request' (duration: 136.464671ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.532748Z","caller":"traceutil/trace.go:171","msg":"trace[119041615] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"136.022481ms","start":"2026-02-25T02:08:08.396705Z","end":"2026-02-25T02:08:08.532727Z","steps":["trace[119041615] 'process raft request' (duration: 135.655183ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.532802Z","caller":"traceutil/trace.go:171","msg":"trace[1442023270] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"135.202464ms","start":"2026-02-25T02:08:08.397569Z","end":"2026-02-25T02:08:08.532772Z","steps":["trace[1442023270] 'process raft request' (duration: 135.064051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:08.532827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.38256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-gs8j7\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-02-25T02:08:08.532871Z","caller":"traceutil/trace.go:171","msg":"trace[1139100555] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-gs8j7; range_end:; response_count:1; response_revision:672; }","duration":"137.448842ms","start":"2026-02-25T02:08:08.39541Z","end":"2026-02-25T02:08:08.532859Z","steps":["trace[1139100555] 'agreement among raft nodes before linearized reading' (duration: 137.343959ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:08.533046Z","caller":"traceutil/trace.go:171","msg":"trace[1665205461] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"135.087071ms","start":"2026-02-25T02:08:08.397949Z","end":"2026-02-25T02:08:08.533036Z","steps":["trace[1665205461] 'process raft request' (duration: 134.759244ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:17.156342Z","caller":"traceutil/trace.go:171","msg":"trace[603406105] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"146.14539ms","start":"2026-02-25T02:08:17.010176Z","end":"2026-02-25T02:08:17.156321Z","steps":["trace[603406105] 'process raft request' (duration: 146.031918ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:18.501687Z","caller":"traceutil/trace.go:171","msg":"trace[2121679506] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"179.325651ms","start":"2026-02-25T02:08:18.32233Z","end":"2026-02-25T02:08:18.501656Z","steps":["trace[2121679506] 'process raft request' (duration: 142.667094ms)","trace[2121679506] 'compare' (duration: 36.527464ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:18.534942Z","caller":"traceutil/trace.go:171","msg":"trace[1564376877] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"158.986485ms","start":"2026-02-25T02:08:18.375934Z","end":"2026-02-25T02:08:18.534921Z","steps":["trace[1564376877] 'process raft request' (duration: 158.811911ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:18.535531Z","caller":"traceutil/trace.go:171","msg":"trace[481309124] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"156.642274ms","start":"2026-02-25T02:08:18.378868Z","end":"2026-02-25T02:08:18.53551Z","steps":["trace[481309124] 'process raft request' (duration: 155.983221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:22.792588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.032631ms","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-25T02:08:22.792633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.112412ms","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-25T02:08:22.792687Z","caller":"traceutil/trace.go:171","msg":"trace[835164699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"128.208514ms","start":"2026-02-25T02:08:22.664458Z","end":"2026-02-25T02:08:22.792667Z","steps":["trace[835164699] 'range keys from in-memory index tree' (duration: 127.766345ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:22.792738Z","caller":"traceutil/trace.go:171","msg":"trace[866518077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:852; }","duration":"101.264065ms","start":"2026-02-25T02:08:22.69145Z","end":"2026-02-25T02:08:22.792714Z","steps":["trace[866518077] 'range keys from in-memory index tree' (duration: 100.880077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:22.79271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.983232ms","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-25T02:08:22.792828Z","caller":"traceutil/trace.go:171","msg":"trace[344025964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"137.160916ms","start":"2026-02-25T02:08:22.655641Z","end":"2026-02-25T02:08:22.792802Z","steps":["trace[344025964] 'range keys from in-memory index tree' (duration: 136.808878ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:22.918021Z","caller":"traceutil/trace.go:171","msg":"trace[1098480378] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"120.538799ms","start":"2026-02-25T02:08:22.797462Z","end":"2026-02-25T02:08:22.918001Z","steps":["trace[1098480378] 'process raft request' (duration: 120.506649ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:22.918166Z","caller":"traceutil/trace.go:171","msg":"trace[1570665978] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"120.694643ms","start":"2026-02-25T02:08:22.797463Z","end":"2026-02-25T02:08:22.918157Z","steps":["trace[1570665978] 'process raft request' (duration: 120.397586ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:22.924088Z","caller":"traceutil/trace.go:171","msg":"trace[1502617544] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"126.58567ms","start":"2026-02-25T02:08:22.797476Z","end":"2026-02-25T02:08:22.924061Z","steps":["trace[1502617544] 'process raft request' (duration: 126.431946ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:24.071709Z","caller":"traceutil/trace.go:171","msg":"trace[492237143] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"292.035332ms","start":"2026-02-25T02:08:23.779649Z","end":"2026-02-25T02:08:24.071684Z","steps":["trace[492237143] 'read index received' (duration: 291.983121ms)","trace[492237143] 'applied index is now lower than readState.Index' (duration: 51.121µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:24.0718Z","caller":"traceutil/trace.go:171","msg":"trace[477128185] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"342.25181ms","start":"2026-02-25T02:08:23.729517Z","end":"2026-02-25T02:08:24.071768Z","steps":["trace[477128185] 'process raft request' (duration: 342.003185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.071884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.213655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:08:24.071921Z","caller":"traceutil/trace.go:171","msg":"trace[883161781] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:858; }","duration":"292.317967ms","start":"2026-02-25T02:08:23.779593Z","end":"2026-02-25T02:08:24.071911Z","steps":["trace[883161781] 'agreement among raft nodes before linearized reading' (duration: 292.191715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.071943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:23.729493Z","time spent":"342.376153ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:08:24.131168Z","caller":"traceutil/trace.go:171","msg":"trace[1355320756] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"259.150266ms","start":"2026-02-25T02:08:23.871994Z","end":"2026-02-25T02:08:24.131145Z","steps":["trace[1355320756] 'process raft request' (duration: 259.024833ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:24.209256Z","caller":"traceutil/trace.go:171","msg":"trace[801341705] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"128.278215ms","start":"2026-02-25T02:08:24.07891Z","end":"2026-02-25T02:08:24.207188Z","steps":["trace[801341705] 'process raft request' (duration: 77.014353ms)","trace[801341705] 'compare' (duration: 51.066787ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:24.249598Z","caller":"traceutil/trace.go:171","msg":"trace[956612238] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"111.930784ms","start":"2026-02-25T02:08:24.13764Z","end":"2026-02-25T02:08:24.249571Z","steps":["trace[956612238] 'process raft request' (duration: 111.814631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.249695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.009218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-7dbd7dcb7f-dxcqj\" ","response":"range_response_count:1 size:4688"} {"level":"info","ts":"2026-02-25T02:08:24.249709Z","caller":"traceutil/trace.go:171","msg":"trace[1288506173] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"115.780117ms","start":"2026-02-25T02:08:24.133888Z","end":"2026-02-25T02:08:24.249668Z","steps":["trace[1288506173] 'process raft request' (duration: 115.44921ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:24.249748Z","caller":"traceutil/trace.go:171","msg":"trace[654330823] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-7dbd7dcb7f-dxcqj; range_end:; response_count:1; response_revision:862; }","duration":"100.11594ms","start":"2026-02-25T02:08:24.149622Z","end":"2026-02-25T02:08:24.249738Z","steps":["trace[654330823] 'agreement among raft nodes before linearized reading' (duration: 99.963207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.255022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.351478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:24.255106Z","caller":"traceutil/trace.go:171","msg":"trace[1055376005] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:863; }","duration":"102.460351ms","start":"2026-02-25T02:08:24.152619Z","end":"2026-02-25T02:08:24.255079Z","steps":["trace[1055376005] 'agreement among raft nodes before linearized reading' (duration: 102.328788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.255313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.848049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:24.25535Z","caller":"traceutil/trace.go:171","msg":"trace[527353700] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:863; }","duration":"102.910241ms","start":"2026-02-25T02:08:24.152429Z","end":"2026-02-25T02:08:24.255339Z","steps":["trace[527353700] 'agreement among raft nodes before linearized reading' (duration: 102.85674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.255491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.782534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:24.25557Z","caller":"traceutil/trace.go:171","msg":"trace[110160610] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:863; }","duration":"100.879866ms","start":"2026-02-25T02:08:24.154673Z","end":"2026-02-25T02:08:24.255553Z","steps":["trace[110160610] 'agreement among raft nodes before linearized reading' (duration: 100.780804ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:25.118913Z","caller":"traceutil/trace.go:171","msg":"trace[1943273520] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"102.205405ms","start":"2026-02-25T02:08:25.016684Z","end":"2026-02-25T02:08:25.118889Z","steps":["trace[1943273520] 'process raft request' (duration: 58.842404ms)","trace[1943273520] 'compare' (duration: 43.286379ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:25.269536Z","caller":"traceutil/trace.go:171","msg":"trace[1632120260] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"105.186ms","start":"2026-02-25T02:08:25.164328Z","end":"2026-02-25T02:08:25.269514Z","steps":["trace[1632120260] 'process raft request' (duration: 103.239618ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:41.579897Z","caller":"traceutil/trace.go:171","msg":"trace[538428154] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"491.861413ms","start":"2026-02-25T02:08:41.088007Z","end":"2026-02-25T02:08:41.579869Z","steps":["trace[538428154] 'process raft request' (duration: 491.70022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:41.580061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:41.087978Z","time spent":"492.004248ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:41.592834Z","caller":"traceutil/trace.go:171","msg":"trace[389030338] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"504.587537ms","start":"2026-02-25T02:08:41.088233Z","end":"2026-02-25T02:08:41.592821Z","steps":["trace[389030338] 'process raft request' (duration: 504.430624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:41.5929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:41.088202Z","time spent":"504.669119ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:41.592811Z","caller":"traceutil/trace.go:171","msg":"trace[608911926] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:985; }","duration":"437.919546ms","start":"2026-02-25T02:08:41.154866Z","end":"2026-02-25T02:08:41.592786Z","steps":["trace[608911926] 'read index received' (duration: 424.831465ms)","trace[608911926] 'applied index is now lower than readState.Index' (duration: 13.087001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:41.592974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.08612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T02:08:41.593018Z","caller":"traceutil/trace.go:171","msg":"trace[2078980366] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:956; }","duration":"438.185582ms","start":"2026-02-25T02:08:41.154821Z","end":"2026-02-25T02:08:41.593006Z","steps":["trace[2078980366] 'agreement among raft nodes before linearized reading' (duration: 438.040859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:41.593053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:41.154803Z","time spent":"438.242363ms","remote":"127.0.0.1:60888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-02-25T02:08:41.593069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.681547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T02:08:41.593095Z","caller":"traceutil/trace.go:171","msg":"trace[1867067535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:956; }","duration":"394.733569ms","start":"2026-02-25T02:08:41.198352Z","end":"2026-02-25T02:08:41.593085Z","steps":["trace[1867067535] 'agreement among raft nodes before linearized reading' (duration: 394.645977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:41.593114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:41.198334Z","time spent":"394.777259ms","remote":"127.0.0.1:32892","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-25T02:08:41.593281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.663841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:08:41.593375Z","caller":"traceutil/trace.go:171","msg":"trace[1484160456] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:956; }","duration":"376.823964ms","start":"2026-02-25T02:08:41.216528Z","end":"2026-02-25T02:08:41.593352Z","steps":["trace[1484160456] 'agreement among raft nodes before linearized reading' (duration: 376.595189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:41.593422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:41.216505Z","time spent":"376.906806ms","remote":"127.0.0.1:32978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":62,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-25T02:08:43.37027Z","caller":"traceutil/trace.go:171","msg":"trace[1318329878] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"121.800635ms","start":"2026-02-25T02:08:43.248433Z","end":"2026-02-25T02:08:43.370233Z","steps":["trace[1318329878] 'process raft request' (duration: 121.674013ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:09:35.343521Z","caller":"traceutil/trace.go:171","msg":"trace[572813975] linearizableReadLoop","detail":"{readStateIndex:1609; appliedIndex:1608; }","duration":"217.815995ms","start":"2026-02-25T02:09:35.125679Z","end":"2026-02-25T02:09:35.343495Z","steps":["trace[572813975] 'read index received' (duration: 217.719293ms)","trace[572813975] 'applied index is now lower than readState.Index' (duration: 95.542µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:09:35.34388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.182652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T02:09:35.343974Z","caller":"traceutil/trace.go:171","msg":"trace[261791561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1540; }","duration":"218.295494ms","start":"2026-02-25T02:09:35.125659Z","end":"2026-02-25T02:09:35.343954Z","steps":["trace[261791561] 'agreement among raft nodes before linearized reading' (duration: 217.996108ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:09:35.343863Z","caller":"traceutil/trace.go:171","msg":"trace[434597989] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"478.417026ms","start":"2026-02-25T02:09:34.865151Z","end":"2026-02-25T02:09:35.343568Z","steps":["trace[434597989] 'process raft request' (duration: 478.165791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:09:35.344302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:09:34.865114Z","time spent":"479.023509ms","remote":"127.0.0.1:32892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:17:18.243276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2108} {"level":"info","ts":"2026-02-25T02:17:18.319882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2108,"took":"75.55902ms","hash":3210362812,"current-db-size-bytes":13991936,"current-db-size":"14 MB","current-db-size-in-use-bytes":4259840,"current-db-size-in-use":"4.3 MB"} {"level":"info","ts":"2026-02-25T02:17:18.319981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3210362812,"revision":2108,"compact-revision":-1} {"level":"info","ts":"2026-02-25T02:22:18.250753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3039} {"level":"info","ts":"2026-02-25T02:22:18.271259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3039,"took":"19.362395ms","hash":2448070557,"current-db-size-bytes":13991936,"current-db-size":"14 MB","current-db-size-in-use-bytes":4636672,"current-db-size-in-use":"4.6 MB"} {"level":"info","ts":"2026-02-25T02:22:18.271328Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2448070557,"revision":3039,"compact-revision":2108} {"level":"warn","ts":"2026-02-25T02:24:36.755765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.420786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:24:36.7561Z","caller":"traceutil/trace.go:171","msg":"trace[946067310] transaction","detail":"{read_only:false; response_revision:4394; number_of_response:1; }","duration":"335.611794ms","start":"2026-02-25T02:24:36.420476Z","end":"2026-02-25T02:24:36.756087Z","steps":["trace[946067310] 'process raft request' (duration: 119.77911ms)","trace[946067310] 'compare' (duration: 215.046827ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:24:36.756077Z","caller":"traceutil/trace.go:171","msg":"trace[2072316618] transaction","detail":"{read_only:false; response_revision:4395; number_of_response:1; }","duration":"335.374589ms","start":"2026-02-25T02:24:36.420673Z","end":"2026-02-25T02:24:36.756047Z","steps":["trace[2072316618] 'process raft request' (duration: 335.183185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:24:36.75616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:24:36.420465Z","time spent":"335.671205ms","remote":"127.0.0.1:32892","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-25T02:24:36.756223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:24:36.420667Z","time spent":"335.483001ms","remote":"127.0.0.1:32892","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-25T02:24:36.755969Z","caller":"traceutil/trace.go:171","msg":"trace[901157113] linearizableReadLoop","detail":"{readStateIndex:4650; appliedIndex:4648; }","duration":"299.088351ms","start":"2026-02-25T02:24:36.456868Z","end":"2026-02-25T02:24:36.755957Z","steps":["trace[901157113] 'read index received' (duration: 83.37797ms)","trace[901157113] 'applied index is now lower than readState.Index' (duration: 215.709751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:24:36.756517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.653743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-02-25T02:24:36.756562Z","caller":"traceutil/trace.go:171","msg":"trace[1372097806] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:4395; }","duration":"299.696224ms","start":"2026-02-25T02:24:36.456852Z","end":"2026-02-25T02:24:36.756548Z","steps":["trace[1372097806] 'agreement among raft nodes before linearized reading' (duration: 299.613942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:24:37.171438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.886783ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T02:24:37.171538Z","caller":"traceutil/trace.go:171","msg":"trace[937069591] linearizableReadLoop","detail":"{readStateIndex:4651; appliedIndex:4650; }","duration":"415.024784ms","start":"2026-02-25T02:24:36.756496Z","end":"2026-02-25T02:24:37.171521Z","steps":["trace[937069591] 'read index received' (duration: 238.034069ms)","trace[937069591] 'applied index is now lower than readState.Index' (duration: 176.989335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:24:37.171625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.351699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:24:37.171685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.640554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-02-25T02:24:37.171661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.044932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"info","ts":"2026-02-25T02:24:37.171708Z","caller":"traceutil/trace.go:171","msg":"trace[1325878745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4395; }","duration":"629.46151ms","start":"2026-02-25T02:24:36.54223Z","end":"2026-02-25T02:24:37.171691Z","steps":["trace[1325878745] 'agreement among raft nodes before linearized reading' (duration: 629.339638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:24:37.171734Z","caller":"traceutil/trace.go:171","msg":"trace[1682459729] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:4395; }","duration":"326.727616ms","start":"2026-02-25T02:24:36.84499Z","end":"2026-02-25T02:24:37.171718Z","steps":["trace[1682459729] 'agreement among raft nodes before linearized reading' (duration: 326.635254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:24:37.171756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:24:36.542219Z","time spent":"629.523771ms","remote":"127.0.0.1:60844","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-25T02:24:37.171786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:24:36.84497Z","time spent":"326.804817ms","remote":"127.0.0.1:32974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":695,"request content":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" "} {"level":"info","ts":"2026-02-25T02:24:37.171734Z","caller":"traceutil/trace.go:171","msg":"trace[207807027] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:4395; }","duration":"642.153044ms","start":"2026-02-25T02:24:36.52957Z","end":"2026-02-25T02:24:37.171723Z","steps":["trace[207807027] 'agreement among raft nodes before linearized reading' (duration: 642.033182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:24:37.171835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:24:36.529546Z","time spent":"642.280727ms","remote":"127.0.0.1:32978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":778,"request content":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" "} {"level":"info","ts":"2026-02-25T02:24:57.059251Z","caller":"traceutil/trace.go:171","msg":"trace[1876321115] linearizableReadLoop","detail":"{readStateIndex:4718; appliedIndex:4717; }","duration":"106.228888ms","start":"2026-02-25T02:24:56.953001Z","end":"2026-02-25T02:24:57.05923Z","steps":["trace[1876321115] 'read index received' (duration: 106.035944ms)","trace[1876321115] 'applied index is now lower than readState.Index' (duration: 192.104µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:24:57.059313Z","caller":"traceutil/trace.go:171","msg":"trace[181448671] transaction","detail":"{read_only:false; response_revision:4458; number_of_response:1; }","duration":"119.477792ms","start":"2026-02-25T02:24:56.939812Z","end":"2026-02-25T02:24:57.05929Z","steps":["trace[181448671] 'process raft request' (duration: 119.276878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:24:57.059482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.459462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T02:24:57.05953Z","caller":"traceutil/trace.go:171","msg":"trace[1660423042] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:4458; }","duration":"106.539955ms","start":"2026-02-25T02:24:56.952979Z","end":"2026-02-25T02:24:57.059518Z","steps":["trace[1660423042] 'agreement among raft nodes before linearized reading' (duration: 106.384801ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:25:05.334628Z","caller":"traceutil/trace.go:171","msg":"trace[1038904448] linearizableReadLoop","detail":"{readStateIndex:4742; appliedIndex:4741; }","duration":"300.359077ms","start":"2026-02-25T02:25:05.034237Z","end":"2026-02-25T02:25:05.334596Z","steps":["trace[1038904448] 'read index received' (duration: 300.155193ms)","trace[1038904448] 'applied index is now lower than readState.Index' (duration: 202.764µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:25:05.334704Z","caller":"traceutil/trace.go:171","msg":"trace[591037098] transaction","detail":"{read_only:false; response_revision:4481; number_of_response:1; }","duration":"309.109022ms","start":"2026-02-25T02:25:05.025554Z","end":"2026-02-25T02:25:05.334663Z","steps":["trace[591037098] 'process raft request' (duration: 308.896018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:25:05.334841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.591342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w7nxl\" ","response":"range_response_count:1 size:18058"} {"level":"warn","ts":"2026-02-25T02:25:05.334882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:25:05.025533Z","time spent":"309.245435ms","remote":"127.0.0.1:32892","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-25T02:25:05.335066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.130199ms","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-25T02:25:05.335181Z","caller":"traceutil/trace.go:171","msg":"trace[20136316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4481; }","duration":"203.344234ms","start":"2026-02-25T02:25:05.131819Z","end":"2026-02-25T02:25:05.335163Z","steps":["trace[20136316] 'agreement among raft nodes before linearized reading' (duration: 202.964126ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:25:05.335463Z","caller":"traceutil/trace.go:171","msg":"trace[1142762091] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w7nxl; range_end:; response_count:1; response_revision:4481; }","duration":"300.705014ms","start":"2026-02-25T02:25:05.034189Z","end":"2026-02-25T02:25:05.334894Z","steps":["trace[1142762091] 'agreement among raft nodes before linearized reading' (duration: 300.50849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:25:05.335522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:25:05.03417Z","time spent":"301.333748ms","remote":"127.0.0.1:32806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18081,"request content":"key:\"/registry/pods/kube-system/cilium-w7nxl\" "}