{"level":"warn","ts":"2026-02-25T01:36:32.51373Z","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-25T01:36:32.513839Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41: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.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41: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-25T01:36:32.513901Z","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-25T01:36:32.513909Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-25T01:36:32.513926Z","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-25T01:36:32.514542Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-25T01:36:32.514629Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41: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-25T01:36:32.519857Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.02947ms"} {"level":"info","ts":"2026-02-25T01:36:32.530736Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-25T01:36:32.530853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T01:36:32.530888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-25T01:36:32.530916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T01:36:32.530941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-25T01:36:32.531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-25T01:36:32.539407Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T01:36:32.540473Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T01:36:32.541301Z","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-25T01:36:32.5429Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T01:36:32.543098Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T01:36:32.54318Z","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-25T01:36:32.543511Z","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-25T01:36:32.543567Z","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-25T01:36:32.543534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-25T01:36:32.546046Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-25T01:36:32.548245Z","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-25T01:36:32.548387Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-25T01:36:32.548804Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-25T01:36:32.549482Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T01:36:32.549578Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T01:36:32.931849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T01:36:32.931924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T01:36:32.931979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-25T01:36:32.932007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-25T01:36:32.932023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-25T01:36:32.932045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-25T01:36:32.932064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-25T01:36:32.933148Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:36:32.934225Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T01:36:32.934505Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:36:32.934751Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:36:32.934874Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T01:36:32.934942Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T01:36:32.934976Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:36:32.935106Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:36:32.935156Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:36:32.936505Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-25T01:36:32.938357Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T01:36:38.019713Z","caller":"traceutil/trace.go:171","msg":"trace[1011061547] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:265; }","duration":"137.370682ms","start":"2026-02-25T01:36:37.882312Z","end":"2026-02-25T01:36:38.019683Z","steps":["trace[1011061547] 'read index received' (duration: 114.33666ms)","trace[1011061547] 'applied index is now lower than readState.Index' (duration: 23.033041ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:36:38.019787Z","caller":"traceutil/trace.go:171","msg":"trace[1762878092] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"308.063588ms","start":"2026-02-25T01:36:37.711694Z","end":"2026-02-25T01:36:38.019757Z","steps":["trace[1762878092] 'process raft request' (duration: 285.008546ms)","trace[1762878092] 'compare' (duration: 22.854898ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:36:38.020121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.610866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:36:38.020295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:36:37.711671Z","time spent":"308.198781ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:36:38.020292Z","caller":"traceutil/trace.go:171","msg":"trace[1705442593] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:260; }","duration":"138.008625ms","start":"2026-02-25T01:36:37.882262Z","end":"2026-02-25T01:36:38.02027Z","steps":["trace[1705442593] 'agreement among raft nodes before linearized reading' (duration: 137.550605ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:36:43.566372Z","caller":"traceutil/trace.go:171","msg":"trace[1650000571] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"113.778008ms","start":"2026-02-25T01:36:43.452567Z","end":"2026-02-25T01:36:43.566345Z","steps":["trace[1650000571] 'process raft request' (duration: 113.576863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:44.577398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.558655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:36:44.577544Z","caller":"traceutil/trace.go:171","msg":"trace[1305098105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:395; }","duration":"154.704887ms","start":"2026-02-25T01:36:44.422774Z","end":"2026-02-25T01:36:44.577479Z","steps":["trace[1305098105] 'range keys from in-memory index tree' (duration: 154.427812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:44.577688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.554518ms","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-25T01:36:44.577747Z","caller":"traceutil/trace.go:171","msg":"trace[244250799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:395; }","duration":"133.64697ms","start":"2026-02-25T01:36:44.444085Z","end":"2026-02-25T01:36:44.577732Z","steps":["trace[244250799] 'range keys from in-memory index tree' (duration: 133.469296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:44.577985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.150887ms","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-25T01:36:44.578005Z","caller":"traceutil/trace.go:171","msg":"trace[343726163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:395; }","duration":"166.212339ms","start":"2026-02-25T01:36:44.411787Z","end":"2026-02-25T01:36:44.577999Z","steps":["trace[343726163] 'range keys from in-memory index tree' (duration: 166.066225ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:36:48.894223Z","caller":"traceutil/trace.go:171","msg":"trace[1715214686] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"289.663377ms","start":"2026-02-25T01:36:48.604521Z","end":"2026-02-25T01:36:48.894184Z","steps":["trace[1715214686] 'process raft request' (duration: 289.453393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:49.1626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.865342ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:36:49.162749Z","caller":"traceutil/trace.go:171","msg":"trace[126821440] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:410; }","duration":"150.039115ms","start":"2026-02-25T01:36:49.012687Z","end":"2026-02-25T01:36:49.162726Z","steps":["trace[126821440] 'range keys from in-memory index tree' (duration: 149.846491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:49.163157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.663744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:36:49.163526Z","caller":"traceutil/trace.go:171","msg":"trace[1105078798] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"558.968959ms","start":"2026-02-25T01:36:48.604519Z","end":"2026-02-25T01:36:49.163488Z","steps":["trace[1105078798] 'process raft request' (duration: 404.660021ms)","trace[1105078798] 'compare' (duration: 153.49173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:36:49.163617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:36:48.604483Z","time spent":"559.096862ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:36:49.16386Z","caller":"traceutil/trace.go:171","msg":"trace[1178213192] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"558.836705ms","start":"2026-02-25T01:36:48.605013Z","end":"2026-02-25T01:36:49.16385Z","steps":["trace[1178213192] 'process raft request' (duration: 558.397795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:49.163919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:36:48.604998Z","time spent":"558.893806ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:36:49.742726Z","caller":"traceutil/trace.go:171","msg":"trace[1969674426] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"311.626405ms","start":"2026-02-25T01:36:49.431076Z","end":"2026-02-25T01:36:49.742703Z","steps":["trace[1969674426] 'process raft request' (duration: 311.505543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:49.742857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:36:49.431048Z","time spent":"311.735488ms","remote":"127.0.0.1:42432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4579,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:36:50.084582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.038209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:36:50.084698Z","caller":"traceutil/trace.go:171","msg":"trace[156706547] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:424; }","duration":"486.323571ms","start":"2026-02-25T01:36:49.598356Z","end":"2026-02-25T01:36:50.08468Z","steps":["trace[156706547] 'read index received' (duration: 144.289432ms)","trace[156706547] 'applied index is now lower than readState.Index' (duration: 342.033099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:36:50.084788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.437144ms","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-25T01:36:50.084815Z","caller":"traceutil/trace.go:171","msg":"trace[438260188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"486.501604ms","start":"2026-02-25T01:36:49.598303Z","end":"2026-02-25T01:36:50.084805Z","steps":["trace[438260188] 'agreement among raft nodes before linearized reading' (duration: 486.423763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:50.084849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:36:49.598285Z","time spent":"486.556666ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:36:50.084885Z","caller":"traceutil/trace.go:171","msg":"trace[535560056] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"550.551429ms","start":"2026-02-25T01:36:49.534296Z","end":"2026-02-25T01:36:50.084848Z","steps":["trace[535560056] 'process raft request' (duration: 360.143712ms)","trace[535560056] 'compare' (duration: 189.895876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:36:50.085062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:36:49.534269Z","time spent":"550.682522ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:36:50.415972Z","caller":"traceutil/trace.go:171","msg":"trace[955050748] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"326.767856ms","start":"2026-02-25T01:36:50.089175Z","end":"2026-02-25T01:36:50.415943Z","steps":["trace[955050748] 'process raft request' (duration: 265.061322ms)","trace[955050748] 'compare' (duration: 61.561411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:36:50.416139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:36:50.08915Z","time spent":"326.895649ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:36:51.218062Z","caller":"traceutil/trace.go:171","msg":"trace[746726200] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"251.078157ms","start":"2026-02-25T01:36:50.966957Z","end":"2026-02-25T01:36:51.218035Z","steps":["trace[746726200] 'process raft request' (duration: 246.519278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:51.478321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.363817ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T01:36:51.478413Z","caller":"traceutil/trace.go:171","msg":"trace[1866906152] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:428; }","duration":"310.221714ms","start":"2026-02-25T01:36:51.168176Z","end":"2026-02-25T01:36:51.478398Z","steps":["trace[1866906152] 'read index received' (duration: 45.313677ms)","trace[1866906152] 'applied index is now lower than readState.Index' (duration: 264.906987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:36:51.478718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.490199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:36:51.478857Z","caller":"traceutil/trace.go:171","msg":"trace[1791995978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"310.716436ms","start":"2026-02-25T01:36:51.168107Z","end":"2026-02-25T01:36:51.478823Z","steps":["trace[1791995978] 'agreement among raft nodes before linearized reading' (duration: 310.419439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:51.478923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:36:51.168075Z","time spent":"310.838588ms","remote":"127.0.0.1:42500","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-25T01:36:51.478822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.641619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-25T01:36:51.479106Z","caller":"traceutil/trace.go:171","msg":"trace[90580023] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:417; }","duration":"257.958747ms","start":"2026-02-25T01:36:51.221127Z","end":"2026-02-25T01:36:51.479086Z","steps":["trace[90580023] 'agreement among raft nodes before linearized reading' (duration: 257.554377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:51.47927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.030522ms","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-25T01:36:51.479352Z","caller":"traceutil/trace.go:171","msg":"trace[852135528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"311.163395ms","start":"2026-02-25T01:36:51.168169Z","end":"2026-02-25T01:36:51.479332Z","steps":["trace[852135528] 'agreement among raft nodes before linearized reading' (duration: 310.464769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:36:51.479409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:36:51.168133Z","time spent":"311.262327ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T01:36:51.719146Z","caller":"traceutil/trace.go:171","msg":"trace[752093764] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"228.904874ms","start":"2026-02-25T01:36:51.490215Z","end":"2026-02-25T01:36:51.71912Z","steps":["trace[752093764] 'process raft request' (duration: 228.833682ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:36:51.719196Z","caller":"traceutil/trace.go:171","msg":"trace[2037970222] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"233.994755ms","start":"2026-02-25T01:36:51.485175Z","end":"2026-02-25T01:36:51.719169Z","steps":["trace[2037970222] 'process raft request' (duration: 154.521025ms)","trace[2037970222] 'compare' (duration: 79.203354ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:36:53.10335Z","caller":"traceutil/trace.go:171","msg":"trace[696469517] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"139.973488ms","start":"2026-02-25T01:36:52.963356Z","end":"2026-02-25T01:36:53.103329Z","steps":["trace[696469517] 'process raft request' (duration: 139.827004ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:36:54.668157Z","caller":"traceutil/trace.go:171","msg":"trace[1103392100] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"162.13203ms","start":"2026-02-25T01:36:54.505999Z","end":"2026-02-25T01:36:54.668131Z","steps":["trace[1103392100] 'process raft request' (duration: 161.996427ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:10.053716Z","caller":"traceutil/trace.go:171","msg":"trace[1548444716] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"879.090111ms","start":"2026-02-25T01:37:09.174613Z","end":"2026-02-25T01:37:10.053703Z","steps":["trace[1548444716] 'process raft request' (duration: 878.977209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:10.05378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.462762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:37:10.053806Z","caller":"traceutil/trace.go:171","msg":"trace[347788300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"401.527163ms","start":"2026-02-25T01:37:09.652271Z","end":"2026-02-25T01:37:10.053798Z","steps":["trace[347788300] 'agreement among raft nodes before linearized reading' (duration: 401.471892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:10.053813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:09.174596Z","time spent":"879.173664ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:10.053833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:09.652256Z","time spent":"401.572104ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:37:10.053705Z","caller":"traceutil/trace.go:171","msg":"trace[1818430032] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"401.38146ms","start":"2026-02-25T01:37:09.652304Z","end":"2026-02-25T01:37:10.053685Z","steps":["trace[1818430032] 'read index received' (duration: 401.277578ms)","trace[1818430032] 'applied index is now lower than readState.Index' (duration: 103.132µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:10.053962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.692207ms","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-25T01:37:10.054011Z","caller":"traceutil/trace.go:171","msg":"trace[1736555901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"142.763289ms","start":"2026-02-25T01:37:09.911234Z","end":"2026-02-25T01:37:10.053997Z","steps":["trace[1736555901] 'agreement among raft nodes before linearized reading' (duration: 142.666156ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:10.181348Z","caller":"traceutil/trace.go:171","msg":"trace[1059506059] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"124.158324ms","start":"2026-02-25T01:37:10.057171Z","end":"2026-02-25T01:37:10.181329Z","steps":["trace[1059506059] 'process raft request' (duration: 122.769174ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:10.182702Z","caller":"traceutil/trace.go:171","msg":"trace[1325864752] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"125.35241ms","start":"2026-02-25T01:37:10.05734Z","end":"2026-02-25T01:37:10.182692Z","steps":["trace[1325864752] 'process raft request' (duration: 125.156696ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:10.870191Z","caller":"traceutil/trace.go:171","msg":"trace[126565504] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"107.240135ms","start":"2026-02-25T01:37:10.762931Z","end":"2026-02-25T01:37:10.870171Z","steps":["trace[126565504] 'process raft request' (duration: 107.123623ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:18.340136Z","caller":"traceutil/trace.go:171","msg":"trace[1595719540] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"131.157206ms","start":"2026-02-25T01:37:18.208953Z","end":"2026-02-25T01:37:18.34011Z","steps":["trace[1595719540] 'read index received' (duration: 130.986932ms)","trace[1595719540] 'applied index is now lower than readState.Index' (duration: 169.324µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:18.340199Z","caller":"traceutil/trace.go:171","msg":"trace[1640865730] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"252.993901ms","start":"2026-02-25T01:37:18.087175Z","end":"2026-02-25T01:37:18.340169Z","steps":["trace[1640865730] 'process raft request' (duration: 252.792367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:18.340425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.621063ms","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-25T01:37:18.340494Z","caller":"traceutil/trace.go:171","msg":"trace[394501703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"126.742186ms","start":"2026-02-25T01:37:18.213735Z","end":"2026-02-25T01:37:18.340477Z","steps":["trace[394501703] 'agreement among raft nodes before linearized reading' (duration: 126.533961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:18.341061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.450132ms","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-25T01:37:18.341129Z","caller":"traceutil/trace.go:171","msg":"trace[128311422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"132.190289ms","start":"2026-02-25T01:37:18.208923Z","end":"2026-02-25T01:37:18.341113Z","steps":["trace[128311422] 'agreement among raft nodes before linearized reading' (duration: 131.321519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:18.648267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.907548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:18.648479Z","caller":"traceutil/trace.go:171","msg":"trace[1943605029] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:565; }","duration":"200.15724ms","start":"2026-02-25T01:37:18.448292Z","end":"2026-02-25T01:37:18.648449Z","steps":["trace[1943605029] 'read index received' (duration: 183.338291ms)","trace[1943605029] 'applied index is now lower than readState.Index' (duration: 16.817539ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:18.648487Z","caller":"traceutil/trace.go:171","msg":"trace[1660204495] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"304.742737ms","start":"2026-02-25T01:37:18.343707Z","end":"2026-02-25T01:37:18.64845Z","steps":["trace[1660204495] 'process raft request' (duration: 79.551743ms)","trace[1660204495] 'compare' (duration: 224.809686ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:18.648601Z","caller":"traceutil/trace.go:171","msg":"trace[412859315] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"303.925598ms","start":"2026-02-25T01:37:18.34466Z","end":"2026-02-25T01:37:18.648586Z","steps":["trace[412859315] 'process raft request' (duration: 303.713344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:18.648643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:18.343686Z","time spent":"304.878171ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:18.648682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:18.344651Z","time spent":"303.985229ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:18.648789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.487428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:18.64884Z","caller":"traceutil/trace.go:171","msg":"trace[1109151858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"200.575159ms","start":"2026-02-25T01:37:18.44825Z","end":"2026-02-25T01:37:18.648825Z","steps":["trace[1109151858] 'agreement among raft nodes before linearized reading' (duration: 200.495377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:18.648863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.982136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b4hxb\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T01:37:18.648901Z","caller":"traceutil/trace.go:171","msg":"trace[1502912942] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b4hxb; range_end:; response_count:1; response_revision:549; }","duration":"121.055677ms","start":"2026-02-25T01:37:18.527835Z","end":"2026-02-25T01:37:18.64889Z","steps":["trace[1502912942] 'agreement among raft nodes before linearized reading' (duration: 120.918044ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:20.448362Z","caller":"traceutil/trace.go:171","msg":"trace[904766359] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"102.429179ms","start":"2026-02-25T01:37:20.345905Z","end":"2026-02-25T01:37:20.448335Z","steps":["trace[904766359] 'process raft request' (duration: 102.282355ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:32.572763Z","caller":"traceutil/trace.go:171","msg":"trace[978254469] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"124.627779ms","start":"2026-02-25T01:37:32.448116Z","end":"2026-02-25T01:37:32.572744Z","steps":["trace[978254469] 'read index received' (duration: 124.477716ms)","trace[978254469] 'applied index is now lower than readState.Index' (duration: 149.023µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:32.572933Z","caller":"traceutil/trace.go:171","msg":"trace[1561905805] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"274.750872ms","start":"2026-02-25T01:37:32.2981Z","end":"2026-02-25T01:37:32.572851Z","steps":["trace[1561905805] 'process raft request' (duration: 274.479255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:32.572949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.802743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:32.573075Z","caller":"traceutil/trace.go:171","msg":"trace[148308022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"124.920756ms","start":"2026-02-25T01:37:32.448093Z","end":"2026-02-25T01:37:32.573014Z","steps":["trace[148308022] 'agreement among raft nodes before linearized reading' (duration: 124.760683ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:39.032403Z","caller":"traceutil/trace.go:171","msg":"trace[1854058374] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"330.871097ms","start":"2026-02-25T01:37:38.7015Z","end":"2026-02-25T01:37:39.032371Z","steps":["trace[1854058374] 'read index received' (duration: 321.025814ms)","trace[1854058374] 'applied index is now lower than readState.Index' (duration: 9.844623ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:39.032429Z","caller":"traceutil/trace.go:171","msg":"trace[907143121] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"339.205794ms","start":"2026-02-25T01:37:38.693194Z","end":"2026-02-25T01:37:39.032399Z","steps":["trace[907143121] 'process raft request' (duration: 329.345042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:39.032528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.02011ms","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-25T01:37:39.032551Z","caller":"traceutil/trace.go:171","msg":"trace[1640097270] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:735; }","duration":"331.07526ms","start":"2026-02-25T01:37:38.701468Z","end":"2026-02-25T01:37:39.032543Z","steps":["trace[1640097270] 'agreement among raft nodes before linearized reading' (duration: 331.020099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:39.032573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:38.701453Z","time spent":"331.113513ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:37:39.03256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:38.69317Z","time spent":"339.316346ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:39.032643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.918967ms","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-25T01:37:39.032706Z","caller":"traceutil/trace.go:171","msg":"trace[1894713816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"252.094291ms","start":"2026-02-25T01:37:38.780595Z","end":"2026-02-25T01:37:39.032689Z","steps":["trace[1894713816] 'agreement among raft nodes before linearized reading' (duration: 251.922057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:39.032708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.727852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:37:39.032782Z","caller":"traceutil/trace.go:171","msg":"trace[540914152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"251.835135ms","start":"2026-02-25T01:37:38.780925Z","end":"2026-02-25T01:37:39.032761Z","steps":["trace[540914152] 'agreement among raft nodes before linearized reading' (duration: 251.651781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:41.193274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251987553229123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:37:41.69423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251987553229123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:37:42.194711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251987553229123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:37:42.614352Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.015775385s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:37:42.614749Z","caller":"traceutil/trace.go:171","msg":"trace[1477608758] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"2.016221215s","start":"2026-02-25T01:37:40.598503Z","end":"2026-02-25T01:37:42.614724Z","steps":["trace[1477608758] 'process raft request' (duration: 2.016062821s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.61489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:40.598486Z","time spent":"2.016333268s","remote":"127.0.0.1:42420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4727,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:42.935835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.552972ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T01:37:42.936197Z","caller":"traceutil/trace.go:171","msg":"trace[1175929011] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:762; }","duration":"2.243549219s","start":"2026-02-25T01:37:40.692627Z","end":"2026-02-25T01:37:42.936176Z","steps":["trace[1175929011] 'read index received' (duration: 1.922029183s)","trace[1175929011] 'applied index is now lower than readState.Index' (duration: 321.519046ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:42.936284Z","caller":"traceutil/trace.go:171","msg":"trace[607670705] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"1.622431761s","start":"2026-02-25T01:37:41.313845Z","end":"2026-02-25T01:37:42.936277Z","steps":["trace[607670705] 'process raft request' (duration: 1.622063443s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.936358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.313821Z","time spent":"1.622481343s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:42.936406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041587788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:42.936452Z","caller":"traceutil/trace.go:171","msg":"trace[535938917] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:740; }","duration":"2.041657199s","start":"2026-02-25T01:37:40.894781Z","end":"2026-02-25T01:37:42.936438Z","steps":["trace[535938917] 'agreement among raft nodes before linearized reading' (duration: 2.041567757s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.936483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:40.894767Z","time spent":"2.04170655s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-02-25T01:37:42.936576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751343046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:42.936611Z","caller":"traceutil/trace.go:171","msg":"trace[178578765] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:740; }","duration":"1.751421398s","start":"2026-02-25T01:37:41.185179Z","end":"2026-02-25T01:37:42.936601Z","steps":["trace[178578765] 'agreement among raft nodes before linearized reading' (duration: 1.751354717s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.936618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681292328s","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-25T01:37:42.936641Z","caller":"traceutil/trace.go:171","msg":"trace[2010631264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:740; }","duration":"1.681314798s","start":"2026-02-25T01:37:41.255318Z","end":"2026-02-25T01:37:42.936633Z","steps":["trace[2010631264] 'agreement among raft nodes before linearized reading' (duration: 1.681231806s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.936648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.75148742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:37:42.936662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.255246Z","time spent":"1.681412011s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:37:42.936679Z","caller":"traceutil/trace.go:171","msg":"trace[845431478] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:740; }","duration":"1.751545551s","start":"2026-02-25T01:37:41.185123Z","end":"2026-02-25T01:37:42.936669Z","steps":["trace[845431478] 'agreement among raft nodes before linearized reading' (duration: 1.75149622s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.93668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740793039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:42.936707Z","caller":"traceutil/trace.go:171","msg":"trace[1840524639] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:740; }","duration":"1.740834089s","start":"2026-02-25T01:37:41.195865Z","end":"2026-02-25T01:37:42.936699Z","steps":["trace[1840524639] 'agreement among raft nodes before linearized reading' (duration: 1.740792329s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.936709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.724919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:37:42.936773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.890752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:37:42.936776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764685307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:42.936794Z","caller":"traceutil/trace.go:171","msg":"trace[1742352451] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:740; }","duration":"881.949173ms","start":"2026-02-25T01:37:42.054839Z","end":"2026-02-25T01:37:42.936788Z","steps":["trace[1742352451] 'agreement among raft nodes before linearized reading' (duration: 881.917913ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:42.936802Z","caller":"traceutil/trace.go:171","msg":"trace[678766574] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:740; }","duration":"1.764723809s","start":"2026-02-25T01:37:41.172071Z","end":"2026-02-25T01:37:42.936794Z","steps":["trace[678766574] 'agreement among raft nodes before linearized reading' (duration: 1.764687928s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:42.936782Z","caller":"traceutil/trace.go:171","msg":"trace[1752408715] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:740; }","duration":"506.849612ms","start":"2026-02-25T01:37:42.429914Z","end":"2026-02-25T01:37:42.936764Z","steps":["trace[1752408715] 'agreement among raft nodes before linearized reading' (duration: 506.722139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.936829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.172068Z","time spent":"1.764753039s","remote":"127.0.0.1:53926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:37:42.936836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488717908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:37:42.936842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751015309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:37:42.936844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:42.429894Z","time spent":"506.939973ms","remote":"127.0.0.1:42458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-02-25T01:37:42.936867Z","caller":"traceutil/trace.go:171","msg":"trace[1998183298] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:740; }","duration":"1.751075401s","start":"2026-02-25T01:37:41.185784Z","end":"2026-02-25T01:37:42.936859Z","steps":["trace[1998183298] 'agreement among raft nodes before linearized reading' (duration: 1.75104039s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.936708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.185109Z","time spent":"1.751589852s","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-25T01:37:42.936896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.185769Z","time spent":"1.751117902s","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-25T01:37:42.93681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.741516525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:37:42.936994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.901141472s","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-25T01:37:42.937012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041882913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:37:42.937056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764990954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:42.937078Z","caller":"traceutil/trace.go:171","msg":"trace[793153258] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:740; }","duration":"1.765011995s","start":"2026-02-25T01:37:41.172059Z","end":"2026-02-25T01:37:42.937071Z","steps":["trace[793153258] 'agreement among raft nodes before linearized reading' (duration: 1.764978054s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:42.937088Z","caller":"traceutil/trace.go:171","msg":"trace[331349027] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:740; }","duration":"2.041970215s","start":"2026-02-25T01:37:40.895099Z","end":"2026-02-25T01:37:42.937069Z","steps":["trace[331349027] 'agreement among raft nodes before linearized reading' (duration: 2.041868912s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:42.937046Z","caller":"traceutil/trace.go:171","msg":"trace[1288407772] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:740; }","duration":"1.741721259s","start":"2026-02-25T01:37:41.195274Z","end":"2026-02-25T01:37:42.936996Z","steps":["trace[1288407772] 'agreement among raft nodes before linearized reading' (duration: 1.741517005s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.937117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041972795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:37:42.937132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:40.895093Z","time spent":"2.042030896s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:37:42.937148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.891417443s","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-25T01:37:42.937154Z","caller":"traceutil/trace.go:171","msg":"trace[46909602] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:740; }","duration":"2.042009506s","start":"2026-02-25T01:37:40.895135Z","end":"2026-02-25T01:37:42.937144Z","steps":["trace[46909602] 'agreement among raft nodes before linearized reading' (duration: 2.041955854s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:42.937169Z","caller":"traceutil/trace.go:171","msg":"trace[95112421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:740; }","duration":"1.891463355s","start":"2026-02-25T01:37:41.0457Z","end":"2026-02-25T01:37:42.937164Z","steps":["trace[95112421] 'agreement among raft nodes before linearized reading' (duration: 1.891413313s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.937187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.045692Z","time spent":"1.891491395s","remote":"127.0.0.1:42500","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-25T01:37:42.937186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:40.89512Z","time spent":"2.042056538s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-02-25T01:37:42.93722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.195268Z","time spent":"1.741884154s","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"info","ts":"2026-02-25T01:37:42.937022Z","caller":"traceutil/trace.go:171","msg":"trace[602022077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:740; }","duration":"1.901203923s","start":"2026-02-25T01:37:41.035811Z","end":"2026-02-25T01:37:42.937015Z","steps":["trace[602022077] 'agreement among raft nodes before linearized reading' (duration: 1.901142172s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.937266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.244644524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T01:37:42.937313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.893467519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T01:37:42.936735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.195861Z","time spent":"1.74086453s","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"info","ts":"2026-02-25T01:37:42.937351Z","caller":"traceutil/trace.go:171","msg":"trace[1035262848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:740; }","duration":"1.893541801s","start":"2026-02-25T01:37:41.043801Z","end":"2026-02-25T01:37:42.937343Z","steps":["trace[1035262848] 'agreement among raft nodes before linearized reading' (duration: 1.893453609s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.937101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.172028Z","time spent":"1.765067556s","remote":"127.0.0.1:53926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-25T01:37:42.937381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.043782Z","time spent":"1.893591282s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:37:42.936856Z","caller":"traceutil/trace.go:171","msg":"trace[516538102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:740; }","duration":"1.488768839s","start":"2026-02-25T01:37:41.448079Z","end":"2026-02-25T01:37:42.936848Z","steps":["trace[516538102] 'agreement among raft nodes before linearized reading' (duration: 1.488746228s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.937474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.448057Z","time spent":"1.489407894s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:37:42.936775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.741389132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:42.93755Z","caller":"traceutil/trace.go:171","msg":"trace[607488006] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:740; }","duration":"1.74216576s","start":"2026-02-25T01:37:41.19537Z","end":"2026-02-25T01:37:42.937535Z","steps":["trace[607488006] 'agreement among raft nodes before linearized reading' (duration: 1.741385002s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.937588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.195366Z","time spent":"1.742210121s","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-02-25T01:37:42.937319Z","caller":"traceutil/trace.go:171","msg":"trace[1067949402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:740; }","duration":"2.244729246s","start":"2026-02-25T01:37:40.692579Z","end":"2026-02-25T01:37:42.937309Z","steps":["trace[1067949402] 'agreement among raft nodes before linearized reading' (duration: 2.244585143s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.937285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.035793Z","time spent":"1.90148443s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:37:42.937709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:40.692561Z","time spent":"2.245096604s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T01:37:42.936813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:42.054813Z","time spent":"881.995115ms","remote":"127.0.0.1:42364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:37:42.93664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.18516Z","time spent":"1.751471369s","remote":"127.0.0.1:53938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:37:42.936743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.762974808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:42.937919Z","caller":"traceutil/trace.go:171","msg":"trace[101504817] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:740; }","duration":"1.764137805s","start":"2026-02-25T01:37:41.173761Z","end":"2026-02-25T01:37:42.937899Z","steps":["trace[101504817] 'agreement among raft nodes before linearized reading' (duration: 1.762962678s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:42.937971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:41.173726Z","time spent":"1.764228797s","remote":"127.0.0.1:53926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"info","ts":"2026-02-25T01:37:43.071842Z","caller":"traceutil/trace.go:171","msg":"trace[186808439] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"128.372253ms","start":"2026-02-25T01:37:42.943445Z","end":"2026-02-25T01:37:43.071817Z","steps":["trace[186808439] 'process raft request' (duration: 126.274166ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:43.078779Z","caller":"traceutil/trace.go:171","msg":"trace[1480498855] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"134.952041ms","start":"2026-02-25T01:37:42.943807Z","end":"2026-02-25T01:37:43.078759Z","steps":["trace[1480498855] 'process raft request' (duration: 134.776157ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:43.078819Z","caller":"traceutil/trace.go:171","msg":"trace[555295463] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"134.796738ms","start":"2026-02-25T01:37:42.944005Z","end":"2026-02-25T01:37:43.078802Z","steps":["trace[555295463] 'process raft request' (duration: 134.705026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:43.078858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.274538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:43.078857Z","caller":"traceutil/trace.go:171","msg":"trace[1642946570] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"134.559482ms","start":"2026-02-25T01:37:42.944278Z","end":"2026-02-25T01:37:43.078837Z","steps":["trace[1642946570] 'process raft request' (duration: 134.45676ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:43.078888Z","caller":"traceutil/trace.go:171","msg":"trace[37824534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"131.34214ms","start":"2026-02-25T01:37:42.947539Z","end":"2026-02-25T01:37:43.078881Z","steps":["trace[37824534] 'agreement among raft nodes before linearized reading' (duration: 131.277149ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:43.078779Z","caller":"traceutil/trace.go:171","msg":"trace[14173926] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:765; }","duration":"131.196686ms","start":"2026-02-25T01:37:42.947563Z","end":"2026-02-25T01:37:43.07876Z","steps":["trace[14173926] 'read index received' (duration: 122.170273ms)","trace[14173926] 'applied index is now lower than readState.Index' (duration: 9.025613ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:43.078921Z","caller":"traceutil/trace.go:171","msg":"trace[1263492835] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"135.043374ms","start":"2026-02-25T01:37:42.94387Z","end":"2026-02-25T01:37:43.078914Z","steps":["trace[1263492835] 'process raft request' (duration: 134.816229ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:45.470725Z","caller":"traceutil/trace.go:171","msg":"trace[1971770746] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:771; }","duration":"373.603369ms","start":"2026-02-25T01:37:45.097092Z","end":"2026-02-25T01:37:45.470696Z","steps":["trace[1971770746] 'read index received' (duration: 373.507747ms)","trace[1971770746] 'applied index is now lower than readState.Index' (duration: 95.052µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:45.470865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.883229ms","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-25T01:37:45.470923Z","caller":"traceutil/trace.go:171","msg":"trace[255968140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"382.975511ms","start":"2026-02-25T01:37:45.08794Z","end":"2026-02-25T01:37:45.470916Z","steps":["trace[255968140] 'agreement among raft nodes before linearized reading' (duration: 382.837228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:45.470946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:45.087926Z","time spent":"383.013772ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T01:37:45.47103Z","caller":"traceutil/trace.go:171","msg":"trace[194530831] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"383.020162ms","start":"2026-02-25T01:37:45.088002Z","end":"2026-02-25T01:37:45.471022Z","steps":["trace[194530831] 'process raft request' (duration: 382.661074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:45.471084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:45.087995Z","time spent":"383.051093ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:37:45.470995Z","caller":"traceutil/trace.go:171","msg":"trace[666145210] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"383.851731ms","start":"2026-02-25T01:37:45.087127Z","end":"2026-02-25T01:37:45.470979Z","steps":["trace[666145210] 'process raft request' (duration: 383.405871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:45.471152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:45.087115Z","time spent":"383.993073ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:37:45.477516Z","caller":"traceutil/trace.go:171","msg":"trace[1840473931] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"377.20881ms","start":"2026-02-25T01:37:45.100283Z","end":"2026-02-25T01:37:45.477492Z","steps":["trace[1840473931] 'process raft request' (duration: 377.058957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:45.477737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:45.100266Z","time spent":"377.369673ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:45.724047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.328563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:45.724203Z","caller":"traceutil/trace.go:171","msg":"trace[769889695] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"249.83122ms","start":"2026-02-25T01:37:45.474351Z","end":"2026-02-25T01:37:45.724183Z","steps":["trace[769889695] 'process raft request' (duration: 10.285202ms)","trace[769889695] 'compare' (duration: 239.19976ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:45.724256Z","caller":"traceutil/trace.go:171","msg":"trace[802544148] linearizableReadLoop","detail":"{readStateIndex:775; appliedIndex:774; }","duration":"198.206776ms","start":"2026-02-25T01:37:45.52597Z","end":"2026-02-25T01:37:45.724176Z","steps":["trace[802544148] 'read index received' (duration: 65.301µs)","trace[802544148] 'applied index is now lower than readState.Index' (duration: 198.138425ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:45.724394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.39516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:45.724434Z","caller":"traceutil/trace.go:171","msg":"trace[1937407500] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:750; }","duration":"198.493422ms","start":"2026-02-25T01:37:45.52593Z","end":"2026-02-25T01:37:45.724423Z","steps":["trace[1937407500] 'agreement among raft nodes before linearized reading' (duration: 198.39111ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:53.665823Z","caller":"traceutil/trace.go:171","msg":"trace[116310570] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"107.355389ms","start":"2026-02-25T01:37:53.558441Z","end":"2026-02-25T01:37:53.665797Z","steps":["trace[116310570] 'process raft request' (duration: 107.209056ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:53.677468Z","caller":"traceutil/trace.go:171","msg":"trace[1640686384] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"116.512415ms","start":"2026-02-25T01:37:53.560935Z","end":"2026-02-25T01:37:53.677448Z","steps":["trace[1640686384] 'process raft request' (duration: 116.339441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:04.32922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.533517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:38:04.329361Z","caller":"traceutil/trace.go:171","msg":"trace[436325568] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"880.8147ms","start":"2026-02-25T01:38:03.44851Z","end":"2026-02-25T01:38:04.329325Z","steps":["trace[436325568] 'read index received' (duration: 459.991877ms)","trace[436325568] 'applied index is now lower than readState.Index' (duration: 420.818893ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:04.329694Z","caller":"traceutil/trace.go:171","msg":"trace[1586831290] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"994.89329ms","start":"2026-02-25T01:38:03.334781Z","end":"2026-02-25T01:38:04.329674Z","steps":["trace[1586831290] 'process raft request' (duration: 573.817091ms)","trace[1586831290] 'compare' (duration: 420.426834ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:04.329796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:03.334761Z","time spent":"994.982991ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:04.330274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.763941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:04.330323Z","caller":"traceutil/trace.go:171","msg":"trace[1817646912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"881.839413ms","start":"2026-02-25T01:38:03.44847Z","end":"2026-02-25T01:38:04.330309Z","steps":["trace[1817646912] 'agreement among raft nodes before linearized reading' (duration: 881.766651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:04.330366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:03.448453Z","time spent":"881.904674ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:38:04.330745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.851538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:38:04.330799Z","caller":"traceutil/trace.go:171","msg":"trace[1513972163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"453.94931ms","start":"2026-02-25T01:38:03.876836Z","end":"2026-02-25T01:38:04.330786Z","steps":["trace[1513972163] 'agreement among raft nodes before linearized reading' (duration: 453.787707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:04.330839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:03.876808Z","time spent":"454.020771ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T01:38:04.330994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.825157ms","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-25T01:38:04.331027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.476492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:38:04.331099Z","caller":"traceutil/trace.go:171","msg":"trace[992791044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"394.971131ms","start":"2026-02-25T01:38:03.936102Z","end":"2026-02-25T01:38:04.331073Z","steps":["trace[992791044] 'agreement among raft nodes before linearized reading' (duration: 394.527751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:04.33113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.348975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:38:04.331154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:03.936082Z","time spent":"395.057782ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:38:04.331206Z","caller":"traceutil/trace.go:171","msg":"trace[1374931503] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:802; }","duration":"267.467876ms","start":"2026-02-25T01:38:04.063719Z","end":"2026-02-25T01:38:04.331187Z","steps":["trace[1374931503] 'agreement among raft nodes before linearized reading' (duration: 267.350844ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:04.331098Z","caller":"traceutil/trace.go:171","msg":"trace[158253891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"502.576455ms","start":"2026-02-25T01:38:03.828502Z","end":"2026-02-25T01:38:04.331078Z","steps":["trace[158253891] 'agreement among raft nodes before linearized reading' (duration: 502.419481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:04.331468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.179555ms","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-25T01:38:04.332509Z","caller":"traceutil/trace.go:171","msg":"trace[1210787030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"396.26007ms","start":"2026-02-25T01:38:03.936226Z","end":"2026-02-25T01:38:04.332486Z","steps":["trace[1210787030] 'agreement among raft nodes before linearized reading' (duration: 394.93226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:04.332649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:03.936204Z","time spent":"396.419954ms","remote":"127.0.0.1:42500","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-25T01:38:04.331415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:03.828483Z","time spent":"502.850933ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:38:04.705309Z","caller":"traceutil/trace.go:171","msg":"trace[1597166852] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"354.528079ms","start":"2026-02-25T01:38:04.350735Z","end":"2026-02-25T01:38:04.705263Z","steps":["trace[1597166852] 'process raft request' (duration: 354.478368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:04.705482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:04.350725Z","time spent":"354.696013ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:04.705329Z","caller":"traceutil/trace.go:171","msg":"trace[898840832] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"354.572801ms","start":"2026-02-25T01:38:04.35072Z","end":"2026-02-25T01:38:04.705293Z","steps":["trace[898840832] 'process raft request' (duration: 341.436245ms)","trace[898840832] 'compare' (duration: 12.945572ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:04.706343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:04.3507Z","time spent":"355.44525ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:04.762084Z","caller":"traceutil/trace.go:171","msg":"trace[1054008511] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"411.272228ms","start":"2026-02-25T01:38:04.350793Z","end":"2026-02-25T01:38:04.762065Z","steps":["trace[1054008511] 'process raft request' (duration: 411.127255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:04.762202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:04.350751Z","time spent":"411.387601ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:04.762268Z","caller":"traceutil/trace.go:171","msg":"trace[1801369238] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"409.067488ms","start":"2026-02-25T01:38:04.353185Z","end":"2026-02-25T01:38:04.762253Z","steps":["trace[1801369238] 'process raft request' (duration: 408.848854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:04.762817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:04.353175Z","time spent":"409.13981ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:07.219431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251987553229326,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:38:07.597435Z","caller":"traceutil/trace.go:171","msg":"trace[355604170] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"878.546458ms","start":"2026-02-25T01:38:06.71886Z","end":"2026-02-25T01:38:07.597406Z","steps":["trace[355604170] 'read index received' (duration: 878.1913ms)","trace[355604170] 'applied index is now lower than readState.Index' (duration: 353.828µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:07.597563Z","caller":"traceutil/trace.go:171","msg":"trace[1486316209] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"883.369057ms","start":"2026-02-25T01:38:06.714121Z","end":"2026-02-25T01:38:07.597491Z","steps":["trace[1486316209] 'process raft request' (duration: 883.03894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:07.597706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:06.714097Z","time spent":"883.530451ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:07.597735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.588598ms","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-25T01:38:07.597769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.339026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:07.597796Z","caller":"traceutil/trace.go:171","msg":"trace[500819776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"149.432379ms","start":"2026-02-25T01:38:07.448357Z","end":"2026-02-25T01:38:07.597789Z","steps":["trace[500819776] 'agreement among raft nodes before linearized reading' (duration: 149.395868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:07.597827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.142528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:38:07.597768Z","caller":"traceutil/trace.go:171","msg":"trace[808518619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"830.65742ms","start":"2026-02-25T01:38:06.767102Z","end":"2026-02-25T01:38:07.597759Z","steps":["trace[808518619] 'agreement among raft nodes before linearized reading' (duration: 830.546938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:07.597937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:06.767087Z","time spent":"830.842064ms","remote":"127.0.0.1:42500","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-25T01:38:07.597734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.248487ms","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-25T01:38:07.598206Z","caller":"traceutil/trace.go:171","msg":"trace[1672114959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"257.80232ms","start":"2026-02-25T01:38:07.340396Z","end":"2026-02-25T01:38:07.598198Z","steps":["trace[1672114959] 'agreement among raft nodes before linearized reading' (duration: 257.254288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:07.598353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.48187ms","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-25T01:38:07.59842Z","caller":"traceutil/trace.go:171","msg":"trace[663554258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"879.572061ms","start":"2026-02-25T01:38:06.718834Z","end":"2026-02-25T01:38:07.598406Z","steps":["trace[663554258] 'agreement among raft nodes before linearized reading' (duration: 878.759132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:07.598455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:06.718823Z","time spent":"879.623422ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:38:07.597965Z","caller":"traceutil/trace.go:171","msg":"trace[831554065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:810; }","duration":"830.25642ms","start":"2026-02-25T01:38:06.767653Z","end":"2026-02-25T01:38:07.597909Z","steps":["trace[831554065] 'agreement among raft nodes before linearized reading' (duration: 830.016195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:07.598654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:06.767649Z","time spent":"830.985636ms","remote":"127.0.0.1:42500","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-25T01:38:07.904367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.518373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:38:07.904588Z","caller":"traceutil/trace.go:171","msg":"trace[1952750472] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"301.942445ms","start":"2026-02-25T01:38:07.602628Z","end":"2026-02-25T01:38:07.90457Z","steps":["trace[1952750472] 'process raft request' (duration: 301.888244ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:07.904596Z","caller":"traceutil/trace.go:171","msg":"trace[1624455949] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"302.16441ms","start":"2026-02-25T01:38:07.602397Z","end":"2026-02-25T01:38:07.904562Z","steps":["trace[1624455949] 'process raft request' (duration: 139.378901ms)","trace[1624455949] 'compare' (duration: 162.40854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:07.904661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:07.602622Z","time spent":"302.008226ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:07.904717Z","caller":"traceutil/trace.go:171","msg":"trace[88244270] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"302.239732ms","start":"2026-02-25T01:38:07.602457Z","end":"2026-02-25T01:38:07.904697Z","steps":["trace[88244270] 'process raft request' (duration: 302.011777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:07.904738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:07.60238Z","time spent":"302.282542ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:07.904805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:07.602453Z","time spent":"302.306623ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:09.773302Z","caller":"traceutil/trace.go:171","msg":"trace[1069284251] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"119.909412ms","start":"2026-02-25T01:38:09.653378Z","end":"2026-02-25T01:38:09.773287Z","steps":["trace[1069284251] 'read index received' (duration: 119.765869ms)","trace[1069284251] 'applied index is now lower than readState.Index' (duration: 142.983µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:09.773449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.054595ms","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-25T01:38:09.773438Z","caller":"traceutil/trace.go:171","msg":"trace[850945140] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"167.392542ms","start":"2026-02-25T01:38:09.606015Z","end":"2026-02-25T01:38:09.773408Z","steps":["trace[850945140] 'process raft request' (duration: 167.180697ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:09.773476Z","caller":"traceutil/trace.go:171","msg":"trace[642566288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:815; }","duration":"120.117076ms","start":"2026-02-25T01:38:09.65335Z","end":"2026-02-25T01:38:09.773467Z","steps":["trace[642566288] 'agreement among raft nodes before linearized reading' (duration: 119.994454ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:09.894072Z","caller":"traceutil/trace.go:171","msg":"trace[1226554600] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"116.149777ms","start":"2026-02-25T01:38:09.777909Z","end":"2026-02-25T01:38:09.894059Z","steps":["trace[1226554600] 'process raft request' (duration: 116.020124ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:19.805355Z","caller":"traceutil/trace.go:171","msg":"trace[56593728] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"110.592673ms","start":"2026-02-25T01:38:19.694741Z","end":"2026-02-25T01:38:19.805333Z","steps":["trace[56593728] 'process raft request' (duration: 109.641573ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:34.398939Z","caller":"traceutil/trace.go:171","msg":"trace[782340335] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"352.684905ms","start":"2026-02-25T01:38:34.046229Z","end":"2026-02-25T01:38:34.398914Z","steps":["trace[782340335] 'read index received' (duration: 352.48613ms)","trace[782340335] 'applied index is now lower than readState.Index' (duration: 197.705µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:34.399088Z","caller":"traceutil/trace.go:171","msg":"trace[1062782036] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"407.295778ms","start":"2026-02-25T01:38:33.991779Z","end":"2026-02-25T01:38:34.399075Z","steps":["trace[1062782036] 'process raft request' (duration: 406.969311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:34.399199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:33.991771Z","time spent":"407.349569ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:34.399345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.063676ms","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-25T01:38:34.399429Z","caller":"traceutil/trace.go:171","msg":"trace[633306500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"238.197169ms","start":"2026-02-25T01:38:34.16121Z","end":"2026-02-25T01:38:34.399407Z","steps":["trace[633306500] 'agreement among raft nodes before linearized reading' (duration: 238.002985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:34.399625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.4049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T01:38:34.399661Z","caller":"traceutil/trace.go:171","msg":"trace[662545734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"353.480092ms","start":"2026-02-25T01:38:34.046172Z","end":"2026-02-25T01:38:34.399652Z","steps":["trace[662545734] 'agreement among raft nodes before linearized reading' (duration: 353.3896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:34.399691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:34.046149Z","time spent":"353.533583ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:38:34.399787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.677221ms","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-25T01:38:34.399842Z","caller":"traceutil/trace.go:171","msg":"trace[227812359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:932; }","duration":"224.730632ms","start":"2026-02-25T01:38:34.175099Z","end":"2026-02-25T01:38:34.39983Z","steps":["trace[227812359] 'agreement among raft nodes before linearized reading' (duration: 224.584949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:34.399871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.823545ms","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-25T01:38:34.399907Z","caller":"traceutil/trace.go:171","msg":"trace[867574522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:932; }","duration":"232.868825ms","start":"2026-02-25T01:38:34.167029Z","end":"2026-02-25T01:38:34.399898Z","steps":["trace[867574522] 'agreement among raft nodes before linearized reading' (duration: 232.785843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:34.829471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.814933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:38:34.829741Z","caller":"traceutil/trace.go:171","msg":"trace[1726069097] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"424.054023ms","start":"2026-02-25T01:38:34.405669Z","end":"2026-02-25T01:38:34.829723Z","steps":["trace[1726069097] 'process raft request' (duration: 423.986952ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:34.829808Z","caller":"traceutil/trace.go:171","msg":"trace[828899969] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"425.309281ms","start":"2026-02-25T01:38:34.404478Z","end":"2026-02-25T01:38:34.829788Z","steps":["trace[828899969] 'process raft request' (duration: 169.098108ms)","trace[828899969] 'compare' (duration: 255.701361ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:34.829863Z","caller":"traceutil/trace.go:171","msg":"trace[2028153402] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"424.985124ms","start":"2026-02-25T01:38:34.404868Z","end":"2026-02-25T01:38:34.829853Z","steps":["trace[2028153402] 'process raft request' (duration: 424.733918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:34.829904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:34.404839Z","time spent":"425.044706ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:34.829928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:34.404453Z","time spent":"425.406843ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:34.830032Z","caller":"traceutil/trace.go:171","msg":"trace[1921388675] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:969; }","duration":"380.976627ms","start":"2026-02-25T01:38:34.449049Z","end":"2026-02-25T01:38:34.830026Z","steps":["trace[1921388675] 'read index received' (duration: 124.611711ms)","trace[1921388675] 'applied index is now lower than readState.Index' (duration: 256.364436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:34.829823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:34.405658Z","time spent":"424.117505ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:34.830165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.582943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:38:34.830183Z","caller":"traceutil/trace.go:171","msg":"trace[1988158416] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:936; }","duration":"367.621214ms","start":"2026-02-25T01:38:34.462556Z","end":"2026-02-25T01:38:34.830178Z","steps":["trace[1988158416] 'agreement among raft nodes before linearized reading' (duration: 367.582293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:34.830165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.09843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:34.830225Z","caller":"traceutil/trace.go:171","msg":"trace[1923109203] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"424.359221ms","start":"2026-02-25T01:38:34.40586Z","end":"2026-02-25T01:38:34.83022Z","steps":["trace[1923109203] 'process raft request' (duration: 423.829749ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:34.83023Z","caller":"traceutil/trace.go:171","msg":"trace[331214710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:936; }","duration":"381.194862ms","start":"2026-02-25T01:38:34.44902Z","end":"2026-02-25T01:38:34.830215Z","steps":["trace[331214710] 'agreement among raft nodes before linearized reading' (duration: 381.089239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:34.83025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:34.405841Z","time spent":"424.395772ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:34.83026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:34.449004Z","time spent":"381.251302ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:38:34.8302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:34.462546Z","time spent":"367.649824ms","remote":"127.0.0.1:50498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:38:35.614765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.980173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:38:35.614862Z","caller":"traceutil/trace.go:171","msg":"trace[1417248354] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"430.620783ms","start":"2026-02-25T01:38:35.184225Z","end":"2026-02-25T01:38:35.614845Z","steps":["trace[1417248354] 'read index received' (duration: 161.431396ms)","trace[1417248354] 'applied index is now lower than readState.Index' (duration: 269.188217ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:35.615102Z","caller":"traceutil/trace.go:171","msg":"trace[678460915] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"751.970582ms","start":"2026-02-25T01:38:34.863118Z","end":"2026-02-25T01:38:35.615089Z","steps":["trace[678460915] 'process raft request' (duration: 482.590631ms)","trace[678460915] 'compare' (duration: 268.86795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:35.615164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:34.863093Z","time spent":"752.039333ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:35.615364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.144355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:35.6154Z","caller":"traceutil/trace.go:171","msg":"trace[542327297] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:937; }","duration":"431.183765ms","start":"2026-02-25T01:38:35.184207Z","end":"2026-02-25T01:38:35.615391Z","steps":["trace[542327297] 'agreement among raft nodes before linearized reading' (duration: 431.120334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:35.615432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:35.184162Z","time spent":"431.260107ms","remote":"127.0.0.1:42366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:38:35.615483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.902092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:38:35.615541Z","caller":"traceutil/trace.go:171","msg":"trace[2058092896] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:937; }","duration":"175.002963ms","start":"2026-02-25T01:38:35.440525Z","end":"2026-02-25T01:38:35.615528Z","steps":["trace[2058092896] 'agreement among raft nodes before linearized reading' (duration: 174.895041ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:48.988296Z","caller":"traceutil/trace.go:171","msg":"trace[131310550] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"374.345516ms","start":"2026-02-25T01:38:48.613929Z","end":"2026-02-25T01:38:48.988275Z","steps":["trace[131310550] 'process raft request' (duration: 374.184992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:48.988419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:48.613907Z","time spent":"374.444008ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:50.194094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079265737s","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-25T01:38:50.194162Z","caller":"traceutil/trace.go:171","msg":"trace[2018572660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"1.079368289s","start":"2026-02-25T01:38:49.114777Z","end":"2026-02-25T01:38:50.194145Z","steps":["trace[2018572660] 'range keys from in-memory index tree' (duration: 1.079136804s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:50.194194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:49.114768Z","time spent":"1.079419611s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:38:50.194271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.033156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:50.194339Z","caller":"traceutil/trace.go:171","msg":"trace[1340502577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"746.148868ms","start":"2026-02-25T01:38:49.448174Z","end":"2026-02-25T01:38:50.194323Z","steps":["trace[1340502577] 'range keys from in-memory index tree' (duration: 745.947854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:50.194367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:49.448144Z","time spent":"746.216779ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:38:50.19442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.770633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:38:50.194481Z","caller":"traceutil/trace.go:171","msg":"trace[85869579] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:973; }","duration":"552.920926ms","start":"2026-02-25T01:38:49.641545Z","end":"2026-02-25T01:38:50.194466Z","steps":["trace[85869579] 'count revisions from in-memory index tree' (duration: 552.661861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:50.19449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080115845s","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-25T01:38:50.194515Z","caller":"traceutil/trace.go:171","msg":"trace[1424964943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"1.080172296s","start":"2026-02-25T01:38:49.114334Z","end":"2026-02-25T01:38:50.194506Z","steps":["trace[1424964943] 'range keys from in-memory index tree' (duration: 1.07984548s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:50.194513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:49.641521Z","time spent":"552.984697ms","remote":"127.0.0.1:42586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:38:50.194533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:49.114316Z","time spent":"1.080212517s","remote":"127.0.0.1:42500","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-25T01:38:50.194547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079845149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:38:50.194578Z","caller":"traceutil/trace.go:171","msg":"trace[342349046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:973; }","duration":"1.079919631s","start":"2026-02-25T01:38:49.114648Z","end":"2026-02-25T01:38:50.194568Z","steps":["trace[342349046] 'range keys from in-memory index tree' (duration: 1.079728227s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:50.194595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:49.114626Z","time spent":"1.079964692s","remote":"127.0.0.1:42500","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-25T01:38:50.194443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135423862s","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-25T01:38:50.194659Z","caller":"traceutil/trace.go:171","msg":"trace[1977169567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"1.135673617s","start":"2026-02-25T01:38:49.058976Z","end":"2026-02-25T01:38:50.19465Z","steps":["trace[1977169567] 'range keys from in-memory index tree' (duration: 1.1353249s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:50.194684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:49.058953Z","time spent":"1.135724038s","remote":"127.0.0.1:42500","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-25T01:38:51.49262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251987553229734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:38:51.713762Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.515248305s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:38:51.714174Z","caller":"traceutil/trace.go:171","msg":"trace[1838801403] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"1.515720464s","start":"2026-02-25T01:38:50.198428Z","end":"2026-02-25T01:38:51.714148Z","steps":["trace[1838801403] 'process raft request' (duration: 1.51554353s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:51.714335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:50.198411Z","time spent":"1.515813596s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:51.716304Z","caller":"traceutil/trace.go:171","msg":"trace[1707078622] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.516642983s","start":"2026-02-25T01:38:50.199632Z","end":"2026-02-25T01:38:51.716275Z","steps":["trace[1707078622] 'process raft request' (duration: 1.516384648s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:51.716469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:50.199611Z","time spent":"1.516778527s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:51.716755Z","caller":"traceutil/trace.go:171","msg":"trace[1615449923] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.516622684s","start":"2026-02-25T01:38:50.200113Z","end":"2026-02-25T01:38:51.716736Z","steps":["trace[1615449923] 'process raft request' (duration: 1.51602102s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:51.716863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:50.200093Z","time spent":"1.516714536s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:51.717731Z","caller":"traceutil/trace.go:171","msg":"trace[1612353665] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.517107624s","start":"2026-02-25T01:38:50.200585Z","end":"2026-02-25T01:38:51.717693Z","steps":["trace[1612353665] 'process raft request' (duration: 1.516080582s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:51.717893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:50.200579Z","time spent":"1.517203716s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:51.720057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.435921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:38:51.720121Z","caller":"traceutil/trace.go:171","msg":"trace[1835419061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"728.521502ms","start":"2026-02-25T01:38:50.991584Z","end":"2026-02-25T01:38:51.720105Z","steps":["trace[1835419061] 'agreement among raft nodes before linearized reading' (duration: 728.191325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:51.720193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:50.99157Z","time spent":"728.585574ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T01:38:51.719742Z","caller":"traceutil/trace.go:171","msg":"trace[2009686294] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1013; }","duration":"728.097364ms","start":"2026-02-25T01:38:50.991613Z","end":"2026-02-25T01:38:51.71971Z","steps":["trace[2009686294] 'read index received' (duration: 722.373402ms)","trace[2009686294] 'applied index is now lower than readState.Index' (duration: 5.692841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:51.720944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.450438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:51.721025Z","caller":"traceutil/trace.go:171","msg":"trace[30143105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"272.566469ms","start":"2026-02-25T01:38:51.448444Z","end":"2026-02-25T01:38:51.721011Z","steps":["trace[30143105] 'agreement among raft nodes before linearized reading' (duration: 272.429016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:54.223781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251987553229762,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:38:54.41039Z","caller":"traceutil/trace.go:171","msg":"trace[662513429] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"687.285275ms","start":"2026-02-25T01:38:53.723077Z","end":"2026-02-25T01:38:54.410363Z","steps":["trace[662513429] 'process raft request' (duration: 687.108902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:54.410604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:53.723058Z","time spent":"687.435228ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:54.595889Z","caller":"traceutil/trace.go:171","msg":"trace[1397981219] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1028; }","duration":"872.720751ms","start":"2026-02-25T01:38:53.723149Z","end":"2026-02-25T01:38:54.59587Z","steps":["trace[1397981219] 'read index received' (duration: 687.026469ms)","trace[1397981219] 'applied index is now lower than readState.Index' (duration: 185.693422ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:54.595998Z","caller":"traceutil/trace.go:171","msg":"trace[908245092] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"872.317732ms","start":"2026-02-25T01:38:53.723673Z","end":"2026-02-25T01:38:54.595991Z","steps":["trace[908245092] 'process raft request' (duration: 872.052307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:54.596081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:53.723662Z","time spent":"872.356813ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:54.596228Z","caller":"traceutil/trace.go:171","msg":"trace[1799610871] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"872.500807ms","start":"2026-02-25T01:38:53.723717Z","end":"2026-02-25T01:38:54.596218Z","steps":["trace[1799610871] 'process raft request' (duration: 872.124448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:54.596278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:53.723701Z","time spent":"872.555129ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:54.596397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.432541ms","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-25T01:38:54.596425Z","caller":"traceutil/trace.go:171","msg":"trace[2112739859] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:989; }","duration":"149.490652ms","start":"2026-02-25T01:38:54.446926Z","end":"2026-02-25T01:38:54.596417Z","steps":["trace[2112739859] 'agreement among raft nodes before linearized reading' (duration: 149.421711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:54.596531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.227495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:38:54.596416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.85309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:38:54.596952Z","caller":"traceutil/trace.go:171","msg":"trace[343272188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"869.409902ms","start":"2026-02-25T01:38:53.727518Z","end":"2026-02-25T01:38:54.596928Z","steps":["trace[343272188] 'agreement among raft nodes before linearized reading' (duration: 868.693747ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:54.596762Z","caller":"traceutil/trace.go:171","msg":"trace[462970921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:989; }","duration":"148.406109ms","start":"2026-02-25T01:38:54.448266Z","end":"2026-02-25T01:38:54.596672Z","steps":["trace[462970921] 'agreement among raft nodes before linearized reading' (duration: 148.068232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:54.597035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:53.727511Z","time spent":"869.504154ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T01:38:54.597216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.031919ms","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-25T01:38:54.597314Z","caller":"traceutil/trace.go:171","msg":"trace[1053000276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:989; }","duration":"874.193563ms","start":"2026-02-25T01:38:53.723107Z","end":"2026-02-25T01:38:54.5973Z","steps":["trace[1053000276] 'agreement among raft nodes before linearized reading' (duration: 873.09378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:54.597412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:53.723084Z","time spent":"874.313896ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:38:56.804313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.073665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T01:38:56.804402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.979755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:56.804432Z","caller":"traceutil/trace.go:171","msg":"trace[1235207485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"356.042317ms","start":"2026-02-25T01:38:56.448381Z","end":"2026-02-25T01:38:56.804424Z","steps":["trace[1235207485] 'range keys from in-memory index tree' (duration: 355.913154ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:56.804418Z","caller":"traceutil/trace.go:171","msg":"trace[1200207978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"119.228607ms","start":"2026-02-25T01:38:56.685176Z","end":"2026-02-25T01:38:56.804404Z","steps":["trace[1200207978] 'range keys from in-memory index tree' (duration: 118.922991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:56.804365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.563399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:38:56.804623Z","caller":"traceutil/trace.go:171","msg":"trace[2029409451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"388.854645ms","start":"2026-02-25T01:38:56.415752Z","end":"2026-02-25T01:38:56.804607Z","steps":["trace[2029409451] 'range keys from in-memory index tree' (duration: 388.443536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:56.80447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:56.448361Z","time spent":"356.097547ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:38:56.804685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.50927ms","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-25T01:38:56.80467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.883358ms","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-25T01:38:56.804752Z","caller":"traceutil/trace.go:171","msg":"trace[1725131898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"203.01219ms","start":"2026-02-25T01:38:56.60172Z","end":"2026-02-25T01:38:56.804732Z","steps":["trace[1725131898] 'range keys from in-memory index tree' (duration: 202.652633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:56.804696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:56.415731Z","time spent":"388.923906ms","remote":"127.0.0.1:42500","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-25T01:38:56.804359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.625243ms","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-25T01:38:56.804881Z","caller":"traceutil/trace.go:171","msg":"trace[1435820658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"203.191584ms","start":"2026-02-25T01:38:56.601678Z","end":"2026-02-25T01:38:56.804869Z","steps":["trace[1435820658] 'range keys from in-memory index tree' (duration: 202.49545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:56.804711Z","caller":"traceutil/trace.go:171","msg":"trace[2136954193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"140.574342ms","start":"2026-02-25T01:38:56.66413Z","end":"2026-02-25T01:38:56.804704Z","steps":["trace[2136954193] 'range keys from in-memory index tree' (duration: 140.388407ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:56.975225Z","caller":"traceutil/trace.go:171","msg":"trace[1070397549] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"167.069436ms","start":"2026-02-25T01:38:56.808132Z","end":"2026-02-25T01:38:56.975201Z","steps":["trace[1070397549] 'process raft request' (duration: 166.942713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:57.958756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251987553229786,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:38:58.459218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251987553229786,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:38:59.448999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999824576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T01:38:59.449103Z","caller":"traceutil/trace.go:171","msg":"trace[970776038] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99999908s","start":"2026-02-25T01:38:57.449082Z","end":"2026-02-25T01:38:59.449081Z","steps":["trace[970776038] 'agreement among raft nodes before linearized reading' (duration: 1.999856147s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:59.449157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:57.449063Z","time spent":"2.000079832s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:38:59.52944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.350408ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:59.529649Z","caller":"traceutil/trace.go:171","msg":"trace[1585826263] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:994; }","duration":"517.564564ms","start":"2026-02-25T01:38:59.012056Z","end":"2026-02-25T01:38:59.52962Z","steps":["trace[1585826263] 'range keys from in-memory index tree' (duration: 517.332858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:59.531012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.099418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:38:59.531534Z","caller":"traceutil/trace.go:171","msg":"trace[829773088] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"2.723227021s","start":"2026-02-25T01:38:56.808278Z","end":"2026-02-25T01:38:59.531505Z","steps":["trace[829773088] 'process raft request' (duration: 2.068687273s)","trace[829773088] 'compare' (duration: 652.74275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:59.531658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:56.808262Z","time spent":"2.723336883s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:59.53166Z","caller":"traceutil/trace.go:171","msg":"trace[1929925471] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"2.723231221s","start":"2026-02-25T01:38:56.808395Z","end":"2026-02-25T01:38:59.531626Z","steps":["trace[1929925471] 'process raft request' (duration: 2.722915724s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:59.531721Z","caller":"traceutil/trace.go:171","msg":"trace[687893627] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"2.722988606s","start":"2026-02-25T01:38:56.808707Z","end":"2026-02-25T01:38:59.531696Z","steps":["trace[687893627] 'process raft request' (duration: 2.72268308s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:59.531769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:56.808374Z","time spent":"2.723346794s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:59.53194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:56.808698Z","time spent":"2.723113219s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:59.532001Z","caller":"traceutil/trace.go:171","msg":"trace[727161631] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"2.72321884s","start":"2026-02-25T01:38:56.808765Z","end":"2026-02-25T01:38:59.531984Z","steps":["trace[727161631] 'process raft request' (duration: 2.722676369s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:59.53221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:56.808756Z","time spent":"2.723408425s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:59.817646Z","caller":"traceutil/trace.go:171","msg":"trace[1512590741] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1037; }","duration":"2.368499142s","start":"2026-02-25T01:38:57.449124Z","end":"2026-02-25T01:38:59.817623Z","steps":["trace[1512590741] 'read index received' (duration: 1.427988298s)","trace[1512590741] 'applied index is now lower than readState.Index' (duration: 940.509774ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:59.817713Z","caller":"traceutil/trace.go:171","msg":"trace[72300899] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"2.608251525s","start":"2026-02-25T01:38:57.209432Z","end":"2026-02-25T01:38:59.817683Z","steps":["trace[72300899] 'process raft request' (duration: 2.530346497s)","trace[72300899] 'compare' (duration: 77.630362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:59.817848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.096086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:38:59.817871Z","caller":"traceutil/trace.go:171","msg":"trace[1469593993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"838.162136ms","start":"2026-02-25T01:38:58.979702Z","end":"2026-02-25T01:38:59.817864Z","steps":["trace[1469593993] 'agreement among raft nodes before linearized reading' (duration: 838.038724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:59.817888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:58.979685Z","time spent":"838.198628ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T01:38:59.817859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:57.209409Z","time spent":"2.608370308s","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:59.818013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.232868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:59.818095Z","caller":"traceutil/trace.go:171","msg":"trace[1059056133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"368.34768ms","start":"2026-02-25T01:38:59.449729Z","end":"2026-02-25T01:38:59.818077Z","steps":["trace[1059056133] 'agreement among raft nodes before linearized reading' (duration: 368.231637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:59.818156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:59.449714Z","time spent":"368.430401ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:39:00.03565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.411279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:39:00.03591Z","caller":"traceutil/trace.go:171","msg":"trace[1729555810] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1042; }","duration":"209.437826ms","start":"2026-02-25T01:38:59.826449Z","end":"2026-02-25T01:39:00.035887Z","steps":["trace[1729555810] 'read index received' (duration: 91.712981ms)","trace[1729555810] 'applied index is now lower than readState.Index' (duration: 117.723215ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:00.036025Z","caller":"traceutil/trace.go:171","msg":"trace[1990594617] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"214.992724ms","start":"2026-02-25T01:38:59.821007Z","end":"2026-02-25T01:39:00.035999Z","steps":["trace[1990594617] 'process raft request' (duration: 97.131436ms)","trace[1990594617] 'compare' (duration: 117.248745ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:00.036061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.570669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:00.036139Z","caller":"traceutil/trace.go:171","msg":"trace[351561126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"209.700652ms","start":"2026-02-25T01:38:59.82642Z","end":"2026-02-25T01:39:00.03612Z","steps":["trace[351561126] 'agreement among raft nodes before linearized reading' (duration: 209.540328ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:00.036017Z","caller":"traceutil/trace.go:171","msg":"trace[261684570] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"213.894612ms","start":"2026-02-25T01:38:59.822082Z","end":"2026-02-25T01:39:00.035976Z","steps":["trace[261684570] 'process raft request' (duration: 213.721578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:01.618351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.699251ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T01:39:01.618472Z","caller":"traceutil/trace.go:171","msg":"trace[441149523] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"309.817753ms","start":"2026-02-25T01:39:01.308634Z","end":"2026-02-25T01:39:01.618452Z","steps":["trace[441149523] 'read index received' (duration: 124.840916ms)","trace[441149523] 'applied index is now lower than readState.Index' (duration: 184.974987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:01.618606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.955585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:01.618721Z","caller":"traceutil/trace.go:171","msg":"trace[988217060] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1001; }","duration":"310.123429ms","start":"2026-02-25T01:39:01.308576Z","end":"2026-02-25T01:39:01.6187Z","steps":["trace[988217060] 'agreement among raft nodes before linearized reading' (duration: 309.937325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:01.618766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:01.308545Z","time spent":"310.21207ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:39:01.618625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.296902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:01.618874Z","caller":"traceutil/trace.go:171","msg":"trace[1298735710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"169.602878ms","start":"2026-02-25T01:39:01.449252Z","end":"2026-02-25T01:39:01.618855Z","steps":["trace[1298735710] 'agreement among raft nodes before linearized reading' (duration: 169.307272ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:02.301009Z","caller":"traceutil/trace.go:171","msg":"trace[556341263] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"677.60329ms","start":"2026-02-25T01:39:01.623305Z","end":"2026-02-25T01:39:02.300909Z","steps":["trace[556341263] 'process raft request' (duration: 677.225792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:02.301277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:01.623296Z","time spent":"677.803174ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:02.301666Z","caller":"traceutil/trace.go:171","msg":"trace[832564726] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1047; }","duration":"261.264339ms","start":"2026-02-25T01:39:02.040337Z","end":"2026-02-25T01:39:02.301601Z","steps":["trace[832564726] 'read index received' (duration: 260.131605ms)","trace[832564726] 'applied index is now lower than readState.Index' (duration: 1.131454ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:02.301708Z","caller":"traceutil/trace.go:171","msg":"trace[536737854] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"678.07156ms","start":"2026-02-25T01:39:01.623594Z","end":"2026-02-25T01:39:02.301666Z","steps":["trace[536737854] 'process raft request' (duration: 677.808644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:02.301856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.500754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T01:39:02.301879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:01.623584Z","time spent":"678.203142ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:02.301891Z","caller":"traceutil/trace.go:171","msg":"trace[2130370740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1005; }","duration":"261.584966ms","start":"2026-02-25T01:39:02.040295Z","end":"2026-02-25T01:39:02.30188Z","steps":["trace[2130370740] 'agreement among raft nodes before linearized reading' (duration: 261.431443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:02.915407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.512964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:39:02.915573Z","caller":"traceutil/trace.go:171","msg":"trace[34633565] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"465.908954ms","start":"2026-02-25T01:39:02.449647Z","end":"2026-02-25T01:39:02.915556Z","steps":["trace[34633565] 'read index received' (duration: 158.124984ms)","trace[34633565] 'applied index is now lower than readState.Index' (duration: 307.78264ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:02.915668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.024477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:02.9157Z","caller":"traceutil/trace.go:171","msg":"trace[510829272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"466.085848ms","start":"2026-02-25T01:39:02.449604Z","end":"2026-02-25T01:39:02.91569Z","steps":["trace[510829272] 'agreement among raft nodes before linearized reading' (duration: 466.003176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:02.915732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:02.449585Z","time spent":"466.14002ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:39:02.915704Z","caller":"traceutil/trace.go:171","msg":"trace[746766363] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"609.272026ms","start":"2026-02-25T01:39:02.306401Z","end":"2026-02-25T01:39:02.915673Z","steps":["trace[746766363] 'process raft request' (duration: 301.416334ms)","trace[746766363] 'compare' (duration: 307.378662ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:02.915856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:02.306379Z","time spent":"609.396948ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:02.915976Z","caller":"traceutil/trace.go:171","msg":"trace[407554329] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"606.487656ms","start":"2026-02-25T01:39:02.309446Z","end":"2026-02-25T01:39:02.915934Z","steps":["trace[407554329] 'process raft request' (duration: 606.058707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:02.916761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:02.30943Z","time spent":"606.636349ms","remote":"127.0.0.1:42334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":848,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:39:04.315285Z","caller":"traceutil/trace.go:171","msg":"trace[1290251320] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"600.682103ms","start":"2026-02-25T01:39:03.714578Z","end":"2026-02-25T01:39:04.31526Z","steps":["trace[1290251320] 'process raft request' (duration: 545.736943ms)","trace[1290251320] 'compare' (duration: 54.4777ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:04.316249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:03.714563Z","time spent":"601.626954ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:08.656357Z","caller":"traceutil/trace.go:171","msg":"trace[1674223326] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"209.250083ms","start":"2026-02-25T01:39:08.447081Z","end":"2026-02-25T01:39:08.656332Z","steps":["trace[1674223326] 'read index received' (duration: 209.015657ms)","trace[1674223326] 'applied index is now lower than readState.Index' (duration: 233.586µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:08.65679Z","caller":"traceutil/trace.go:171","msg":"trace[504700134] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"210.816836ms","start":"2026-02-25T01:39:08.445953Z","end":"2026-02-25T01:39:08.656769Z","steps":["trace[504700134] 'process raft request' (duration: 210.250783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:08.657322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.202453ms","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-25T01:39:08.657309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.450744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:08.657437Z","caller":"traceutil/trace.go:171","msg":"trace[1874523740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1040; }","duration":"210.376487ms","start":"2026-02-25T01:39:08.447036Z","end":"2026-02-25T01:39:08.657413Z","steps":["trace[1874523740] 'agreement among raft nodes before linearized reading' (duration: 210.06408ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:08.657479Z","caller":"traceutil/trace.go:171","msg":"trace[519101067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1040; }","duration":"206.659088ms","start":"2026-02-25T01:39:08.450782Z","end":"2026-02-25T01:39:08.657441Z","steps":["trace[519101067] 'agreement among raft nodes before linearized reading' (duration: 206.441304ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:09.767927Z","caller":"traceutil/trace.go:171","msg":"trace[1907917495] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"376.361448ms","start":"2026-02-25T01:39:09.391548Z","end":"2026-02-25T01:39:09.76791Z","steps":["trace[1907917495] 'process raft request' (duration: 376.283507ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:09.767966Z","caller":"traceutil/trace.go:171","msg":"trace[1820970680] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1090; }","duration":"373.59827ms","start":"2026-02-25T01:39:09.39434Z","end":"2026-02-25T01:39:09.767938Z","steps":["trace[1820970680] 'read index received' (duration: 373.59131ms)","trace[1820970680] 'applied index is now lower than readState.Index' (duration: 5.75µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:09.768017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:09.391502Z","time spent":"376.473581ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:39:09.768103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.753413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:09.768163Z","caller":"traceutil/trace.go:171","msg":"trace[266104890] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1045; }","duration":"373.852295ms","start":"2026-02-25T01:39:09.394296Z","end":"2026-02-25T01:39:09.768148Z","steps":["trace[266104890] 'agreement among raft nodes before linearized reading' (duration: 373.726093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:09.768202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:09.39426Z","time spent":"373.932966ms","remote":"127.0.0.1:42462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:39:09.769692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.488886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:09.76972Z","caller":"traceutil/trace.go:171","msg":"trace[1678305133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"317.561857ms","start":"2026-02-25T01:39:09.452152Z","end":"2026-02-25T01:39:09.769714Z","steps":["trace[1678305133] 'agreement among raft nodes before linearized reading' (duration: 317.487046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:09.769739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:09.452126Z","time spent":"317.607759ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:39:20.603525Z","caller":"traceutil/trace.go:171","msg":"trace[528728541] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"119.39814ms","start":"2026-02-25T01:39:20.4841Z","end":"2026-02-25T01:39:20.603498Z","steps":["trace[528728541] 'read index received' (duration: 119.116794ms)","trace[528728541] 'applied index is now lower than readState.Index' (duration: 280.186µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:20.603927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.800768ms","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-25T01:39:20.603973Z","caller":"traceutil/trace.go:171","msg":"trace[1442482518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"119.897841ms","start":"2026-02-25T01:39:20.484064Z","end":"2026-02-25T01:39:20.603962Z","steps":["trace[1442482518] 'agreement among raft nodes before linearized reading' (duration: 119.533773ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:20.604166Z","caller":"traceutil/trace.go:171","msg":"trace[1049343831] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"127.519434ms","start":"2026-02-25T01:39:20.476617Z","end":"2026-02-25T01:39:20.604136Z","steps":["trace[1049343831] 'process raft request' (duration: 126.705186ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:55.223417Z","caller":"traceutil/trace.go:171","msg":"trace[841066957] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"106.506019ms","start":"2026-02-25T01:39:55.11688Z","end":"2026-02-25T01:39:55.223386Z","steps":["trace[841066957] 'process raft request' (duration: 106.310714ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:40:41.439753Z","caller":"traceutil/trace.go:171","msg":"trace[155249579] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"147.135021ms","start":"2026-02-25T01:40:41.292594Z","end":"2026-02-25T01:40:41.439729Z","steps":["trace[155249579] 'process raft request' (duration: 113.121653ms)","trace[155249579] 'compare' (duration: 33.892626ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:44.626065Z","caller":"traceutil/trace.go:171","msg":"trace[1134863412] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"125.793585ms","start":"2026-02-25T01:40:44.500242Z","end":"2026-02-25T01:40:44.626036Z","steps":["trace[1134863412] 'process raft request' (duration: 125.656832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:19.070577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.159363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:19.07064Z","caller":"traceutil/trace.go:171","msg":"trace[1866215331] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1873; }","duration":"251.277865ms","start":"2026-02-25T01:42:18.819349Z","end":"2026-02-25T01:42:19.070627Z","steps":["trace[1866215331] 'count revisions from in-memory index tree' (duration: 251.073481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:20.125879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.0884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-25T01:42:20.125972Z","caller":"traceutil/trace.go:171","msg":"trace[936316949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1874; }","duration":"325.232263ms","start":"2026-02-25T01:42:19.80072Z","end":"2026-02-25T01:42:20.125952Z","steps":["trace[936316949] 'range keys from in-memory index tree' (duration: 324.885505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:20.126045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:19.800701Z","time spent":"325.334535ms","remote":"127.0.0.1:42500","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-25T01:42:20.126291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.743798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-02-25T01:42:20.1269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.291921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:42:20.126976Z","caller":"traceutil/trace.go:171","msg":"trace[2129566424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"662.405904ms","start":"2026-02-25T01:42:19.464549Z","end":"2026-02-25T01:42:20.126955Z","steps":["trace[2129566424] 'range keys from in-memory index tree' (duration: 661.682648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:20.127032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:19.464527Z","time spent":"662.496246ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:42:20.128125Z","caller":"traceutil/trace.go:171","msg":"trace[577653989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1874; }","duration":"466.882301ms","start":"2026-02-25T01:42:19.659498Z","end":"2026-02-25T01:42:20.12638Z","steps":["trace[577653989] 'range keys from in-memory index tree' (duration: 466.567484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:20.128179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:19.659481Z","time spent":"468.68477ms","remote":"127.0.0.1:42500","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-25T01:42:20.126286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.496296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-25T01:42:20.129007Z","caller":"traceutil/trace.go:171","msg":"trace[734620212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1874; }","duration":"113.264776ms","start":"2026-02-25T01:42:20.015678Z","end":"2026-02-25T01:42:20.128943Z","steps":["trace[734620212] 'range keys from in-memory index tree' (duration: 110.310281ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:20.313994Z","caller":"traceutil/trace.go:171","msg":"trace[438352368] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"165.491653ms","start":"2026-02-25T01:42:20.148472Z","end":"2026-02-25T01:42:20.313963Z","steps":["trace[438352368] 'process raft request' (duration: 165.265048ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:20.323281Z","caller":"traceutil/trace.go:171","msg":"trace[732515187] linearizableReadLoop","detail":"{readStateIndex:1962; appliedIndex:1958; }","duration":"135.074898ms","start":"2026-02-25T01:42:20.188182Z","end":"2026-02-25T01:42:20.323257Z","steps":["trace[732515187] 'read index received' (duration: 125.561021ms)","trace[732515187] 'applied index is now lower than readState.Index' (duration: 9.512856ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:20.323511Z","caller":"traceutil/trace.go:171","msg":"trace[756066011] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"174.683123ms","start":"2026-02-25T01:42:20.148764Z","end":"2026-02-25T01:42:20.323447Z","steps":["trace[756066011] 'process raft request' (duration: 174.262513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:20.323684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.476396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-25T01:42:20.323844Z","caller":"traceutil/trace.go:171","msg":"trace[846684985] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1878; }","duration":"135.684501ms","start":"2026-02-25T01:42:20.188141Z","end":"2026-02-25T01:42:20.323826Z","steps":["trace[846684985] 'agreement among raft nodes before linearized reading' (duration: 135.263532ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:20.324045Z","caller":"traceutil/trace.go:171","msg":"trace[1922015318] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"174.396026ms","start":"2026-02-25T01:42:20.14963Z","end":"2026-02-25T01:42:20.324026Z","steps":["trace[1922015318] 'process raft request' (duration: 173.509546ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:20.324094Z","caller":"traceutil/trace.go:171","msg":"trace[500154248] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"174.415606ms","start":"2026-02-25T01:42:20.149669Z","end":"2026-02-25T01:42:20.324084Z","steps":["trace[500154248] 'process raft request' (duration: 173.543388ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:20.52521Z","caller":"traceutil/trace.go:171","msg":"trace[1609926299] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"195.255456ms","start":"2026-02-25T01:42:20.329911Z","end":"2026-02-25T01:42:20.525167Z","steps":["trace[1609926299] 'process raft request' (duration: 101.797458ms)","trace[1609926299] 'compare' (duration: 93.194523ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:20.906185Z","caller":"traceutil/trace.go:171","msg":"trace[985835374] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"377.996572ms","start":"2026-02-25T01:42:20.528164Z","end":"2026-02-25T01:42:20.906161Z","steps":["trace[985835374] 'read index received' (duration: 377.827499ms)","trace[985835374] 'applied index is now lower than readState.Index' (duration: 167.863µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:20.906245Z","caller":"traceutil/trace.go:171","msg":"trace[1018233975] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"471.365589ms","start":"2026-02-25T01:42:20.434818Z","end":"2026-02-25T01:42:20.906183Z","steps":["trace[1018233975] 'process raft request' (duration: 471.196295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:20.906534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.34201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-02-25T01:42:20.906565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.620476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-02-25T01:42:20.906567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:20.434799Z","time spent":"471.522623ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:20.906613Z","caller":"traceutil/trace.go:171","msg":"trace[415885166] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1880; }","duration":"366.721019ms","start":"2026-02-25T01:42:20.539881Z","end":"2026-02-25T01:42:20.906602Z","steps":["trace[415885166] 'agreement among raft nodes before linearized reading' (duration: 366.454503ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:20.906617Z","caller":"traceutil/trace.go:171","msg":"trace[601864149] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1880; }","duration":"378.472413ms","start":"2026-02-25T01:42:20.528125Z","end":"2026-02-25T01:42:20.906597Z","steps":["trace[601864149] 'agreement among raft nodes before linearized reading' (duration: 378.197927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:20.906665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:20.528109Z","time spent":"378.547154ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T01:42:20.906651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:20.539863Z","time spent":"366.779101ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-25T01:42:21.429185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.128992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:42:21.429715Z","caller":"traceutil/trace.go:171","msg":"trace[1279249260] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"517.087475ms","start":"2026-02-25T01:42:20.912592Z","end":"2026-02-25T01:42:21.42968Z","steps":["trace[1279249260] 'process raft request' (duration: 96.380211ms)","trace[1279249260] 'compare' (duration: 419.951468ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:21.429752Z","caller":"traceutil/trace.go:171","msg":"trace[752811744] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"513.55742ms","start":"2026-02-25T01:42:20.916133Z","end":"2026-02-25T01:42:21.42969Z","steps":["trace[752811744] 'process raft request' (duration: 513.157641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:21.429919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:20.912572Z","time spent":"517.2838ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:42:21.43002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:42:20.916105Z","time spent":"513.857447ms","remote":"127.0.0.1:42500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:42:21.495165Z","caller":"traceutil/trace.go:171","msg":"trace[108288795] linearizableReadLoop","detail":"{readStateIndex:1967; appliedIndex:1964; }","duration":"242.824023ms","start":"2026-02-25T01:42:21.252316Z","end":"2026-02-25T01:42:21.49514Z","steps":["trace[108288795] 'read index received' (duration: 27.183377ms)","trace[108288795] 'applied index is now lower than readState.Index' (duration: 215.639366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:21.495552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.217933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T01:42:21.495647Z","caller":"traceutil/trace.go:171","msg":"trace[871464595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1882; }","duration":"243.345625ms","start":"2026-02-25T01:42:21.252286Z","end":"2026-02-25T01:42:21.495632Z","steps":["trace[871464595] 'agreement among raft nodes before linearized reading' (duration: 243.021238ms)"],"step_count":1}