{"level":"warn","ts":"2026-02-25T17:03:59.876774Z","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-25T17:03:59.876857Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.112: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.19.213.112:2380","--initial-cluster=instance=https://199.19.213.112:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.112:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.112: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-25T17:03:59.876918Z","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-25T17:03:59.876927Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.112:2380"]} {"level":"info","ts":"2026-02-25T17:03:59.876944Z","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-25T17:03:59.877811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"]} {"level":"info","ts":"2026-02-25T17:03:59.877899Z","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.19.213.112:2380"],"listen-peer-urls":["https://199.19.213.112:2380"],"advertise-client-urls":["https://199.19.213.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.112: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-25T17:03:59.884535Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.799612ms"} {"level":"info","ts":"2026-02-25T17:03:59.89284Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"197930edc5411b2e","cluster-id":"8165613f50a6d530"} {"level":"info","ts":"2026-02-25T17:03:59.892925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T17:03:59.892956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became follower at term 0"} {"level":"info","ts":"2026-02-25T17:03:59.89297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 197930edc5411b2e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T17:03:59.892988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became follower at term 1"} {"level":"info","ts":"2026-02-25T17:03:59.893023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=(1835552120904948526)"} {"level":"warn","ts":"2026-02-25T17:03:59.898499Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T17:03:59.902631Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T17:03:59.911349Z","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-25T17:03:59.915598Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"197930edc5411b2e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T17:03:59.915943Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"197930edc5411b2e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T17:03:59.915981Z","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-25T17:03:59.916126Z","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-25T17:03:59.916171Z","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-25T17:03:59.919576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=(1835552120904948526)"} {"level":"info","ts":"2026-02-25T17:03:59.919714Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8165613f50a6d530","local-member-id":"197930edc5411b2e","added-peer-id":"197930edc5411b2e","added-peer-peer-urls":["https://199.19.213.112:2380"]} {"level":"info","ts":"2026-02-25T17:03:59.921562Z","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-25T17:03:59.921755Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"197930edc5411b2e","initial-advertise-peer-urls":["https://199.19.213.112:2380"],"listen-peer-urls":["https://199.19.213.112:2380"],"advertise-client-urls":["https://199.19.213.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T17:03:59.921802Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T17:03:59.921782Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.112:2380"} {"level":"info","ts":"2026-02-25T17:03:59.921851Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.112:2380"} {"level":"info","ts":"2026-02-25T17:04:00.093555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T17:04:00.093624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T17:04:00.093702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e received MsgPreVoteResp from 197930edc5411b2e at term 1"} {"level":"info","ts":"2026-02-25T17:04:00.093729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became candidate at term 2"} {"level":"info","ts":"2026-02-25T17:04:00.093747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e received MsgVoteResp from 197930edc5411b2e at term 2"} {"level":"info","ts":"2026-02-25T17:04:00.093764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became leader at term 2"} {"level":"info","ts":"2026-02-25T17:04:00.093785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 197930edc5411b2e elected leader 197930edc5411b2e at term 2"} {"level":"info","ts":"2026-02-25T17:04:00.095649Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"197930edc5411b2e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.112:2379]}","request-path":"/0/members/197930edc5411b2e/attributes","cluster-id":"8165613f50a6d530","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T17:04:00.095874Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:04:00.096017Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T17:04:00.09605Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T17:04:00.096058Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:00.095908Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:04:00.097135Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8165613f50a6d530","local-member-id":"197930edc5411b2e","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:00.097278Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:00.097362Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:00.100228Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T17:04:00.104503Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.112:2379"} {"level":"info","ts":"2026-02-25T17:04:08.230388Z","caller":"traceutil/trace.go:171","msg":"trace[1522440831] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"147.451249ms","start":"2026-02-25T17:04:08.082897Z","end":"2026-02-25T17:04:08.230348Z","steps":["trace[1522440831] 'process raft request' (duration: 147.160992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:08.439312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.972272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T17:04:08.439639Z","caller":"traceutil/trace.go:171","msg":"trace[217591219] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:297; }","duration":"108.421422ms","start":"2026-02-25T17:04:08.331197Z","end":"2026-02-25T17:04:08.439618Z","steps":["trace[217591219] 'range keys from in-memory index tree' (duration: 107.838269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:17.522862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.221091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-25T17:04:17.522989Z","caller":"traceutil/trace.go:171","msg":"trace[1564040984] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:374; }","duration":"342.353284ms","start":"2026-02-25T17:04:17.180614Z","end":"2026-02-25T17:04:17.522968Z","steps":["trace[1564040984] 'range keys from in-memory index tree' (duration: 342.109608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:17.523031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:17.180553Z","time spent":"342.468956ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4057,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-25T17:04:17.727775Z","caller":"traceutil/trace.go:171","msg":"trace[687016306] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"109.594899ms","start":"2026-02-25T17:04:17.618159Z","end":"2026-02-25T17:04:17.727753Z","steps":["trace[687016306] 'process raft request' (duration: 109.477656ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:17.98732Z","caller":"traceutil/trace.go:171","msg":"trace[486458774] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"210.128421ms","start":"2026-02-25T17:04:17.777156Z","end":"2026-02-25T17:04:17.987284Z","steps":["trace[486458774] 'process raft request' (duration: 175.828073ms)","trace[486458774] 'compare' (duration: 34.074013ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:17.9874Z","caller":"traceutil/trace.go:171","msg":"trace[1197183425] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"209.039297ms","start":"2026-02-25T17:04:17.778333Z","end":"2026-02-25T17:04:17.987372Z","steps":["trace[1197183425] 'process raft request' (duration: 208.850833ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:17.987399Z","caller":"traceutil/trace.go:171","msg":"trace[1688027298] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"207.990602ms","start":"2026-02-25T17:04:17.779386Z","end":"2026-02-25T17:04:17.987377Z","steps":["trace[1688027298] 'process raft request' (duration: 207.936151ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:17.987456Z","caller":"traceutil/trace.go:171","msg":"trace[21022761] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"208.849483ms","start":"2026-02-25T17:04:17.778578Z","end":"2026-02-25T17:04:17.987427Z","steps":["trace[21022761] 'process raft request' (duration: 208.695139ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:22.533174Z","caller":"traceutil/trace.go:171","msg":"trace[167919079] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"135.434256ms","start":"2026-02-25T17:04:22.397716Z","end":"2026-02-25T17:04:22.53315Z","steps":["trace[167919079] 'process raft request' (duration: 135.315544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.447618Z","caller":"traceutil/trace.go:171","msg":"trace[209708199] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"689.737072ms","start":"2026-02-25T17:04:22.757862Z","end":"2026-02-25T17:04:23.447599Z","steps":["trace[209708199] 'process raft request' (duration: 689.610759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.448038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:22.757834Z","time spent":"689.839144ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:23.609854Z","caller":"traceutil/trace.go:171","msg":"trace[1348103681] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:429; }","duration":"542.046099ms","start":"2026-02-25T17:04:23.067783Z","end":"2026-02-25T17:04:23.609829Z","steps":["trace[1348103681] 'read index received' (duration: 379.722083ms)","trace[1348103681] 'applied index is now lower than readState.Index' (duration: 162.323186ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:23.609989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.191223ms","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-25T17:04:23.610029Z","caller":"traceutil/trace.go:171","msg":"trace[282377979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:417; }","duration":"542.281715ms","start":"2026-02-25T17:04:23.067735Z","end":"2026-02-25T17:04:23.610017Z","steps":["trace[282377979] 'agreement among raft nodes before linearized reading' (duration: 542.193413ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.610003Z","caller":"traceutil/trace.go:171","msg":"trace[952660155] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"834.630632ms","start":"2026-02-25T17:04:22.775322Z","end":"2026-02-25T17:04:23.609953Z","steps":["trace[952660155] 'process raft request' (duration: 832.808621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.610071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:23.067716Z","time spent":"542.346916ms","remote":"127.0.0.1:55074","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-25T17:04:23.610187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:22.775299Z","time spent":"834.789116ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:25.597582Z","caller":"traceutil/trace.go:171","msg":"trace[415874972] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"139.200251ms","start":"2026-02-25T17:04:25.458363Z","end":"2026-02-25T17:04:25.597563Z","steps":["trace[415874972] 'process raft request' (duration: 139.088347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:28.845693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.097987ms","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-25T17:04:28.845792Z","caller":"traceutil/trace.go:171","msg":"trace[1624408308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"288.190419ms","start":"2026-02-25T17:04:28.557561Z","end":"2026-02-25T17:04:28.845752Z","steps":["trace[1624408308] 'range keys from in-memory index tree' (duration: 287.917452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:28.845914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.95015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-25T17:04:28.845978Z","caller":"traceutil/trace.go:171","msg":"trace[1789833969] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:429; }","duration":"112.038691ms","start":"2026-02-25T17:04:28.733923Z","end":"2026-02-25T17:04:28.845961Z","steps":["trace[1789833969] 'range keys from in-memory index tree' (duration: 111.876948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:28.846103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.290413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:28.846138Z","caller":"traceutil/trace.go:171","msg":"trace[451189368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"114.352464ms","start":"2026-02-25T17:04:28.731775Z","end":"2026-02-25T17:04:28.846127Z","steps":["trace[451189368] 'range keys from in-memory index tree' (duration: 114.235292ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:33.7894Z","caller":"traceutil/trace.go:171","msg":"trace[1117032127] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"100.438517ms","start":"2026-02-25T17:04:33.688936Z","end":"2026-02-25T17:04:33.789374Z","steps":["trace[1117032127] 'read index received' (duration: 100.358935ms)","trace[1117032127] 'applied index is now lower than readState.Index' (duration: 78.472µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:33.789553Z","caller":"traceutil/trace.go:171","msg":"trace[1555557125] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"135.163459ms","start":"2026-02-25T17:04:33.65438Z","end":"2026-02-25T17:04:33.789543Z","steps":["trace[1555557125] 'process raft request' (duration: 134.854022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:33.790053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.088341ms","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-25T17:04:33.790153Z","caller":"traceutil/trace.go:171","msg":"trace[1803978051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"101.195404ms","start":"2026-02-25T17:04:33.688931Z","end":"2026-02-25T17:04:33.790126Z","steps":["trace[1803978051] 'agreement among raft nodes before linearized reading' (duration: 100.951078ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:33.903567Z","caller":"traceutil/trace.go:171","msg":"trace[470582078] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"103.445763ms","start":"2026-02-25T17:04:33.800096Z","end":"2026-02-25T17:04:33.903542Z","steps":["trace[470582078] 'read index received' (duration: 72.503646ms)","trace[470582078] 'applied index is now lower than readState.Index' (duration: 30.941197ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:33.903672Z","caller":"traceutil/trace.go:171","msg":"trace[226171985] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"109.015332ms","start":"2026-02-25T17:04:33.794608Z","end":"2026-02-25T17:04:33.903623Z","steps":["trace[226171985] 'process raft request' (duration: 78.002563ms)","trace[226171985] 'compare' (duration: 30.821324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:33.903791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.687661ms","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-25T17:04:33.903845Z","caller":"traceutil/trace.go:171","msg":"trace[19074159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"103.781332ms","start":"2026-02-25T17:04:33.800048Z","end":"2026-02-25T17:04:33.903829Z","steps":["trace[19074159] 'agreement among raft nodes before linearized reading' (duration: 103.635659ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:40.039922Z","caller":"traceutil/trace.go:171","msg":"trace[2114182083] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"110.026576ms","start":"2026-02-25T17:04:39.92986Z","end":"2026-02-25T17:04:40.039887Z","steps":["trace[2114182083] 'process raft request' (duration: 109.479633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:40.576607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.899967ms","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-25T17:04:40.576728Z","caller":"traceutil/trace.go:171","msg":"trace[828108991] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:491; }","duration":"143.0429ms","start":"2026-02-25T17:04:40.433668Z","end":"2026-02-25T17:04:40.576711Z","steps":["trace[828108991] 'range keys from in-memory index tree' (duration: 142.872556ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.074372Z","caller":"traceutil/trace.go:171","msg":"trace[69996002] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"342.50049ms","start":"2026-02-25T17:04:41.731849Z","end":"2026-02-25T17:04:42.07435Z","steps":["trace[69996002] 'read index received' (duration: 342.294465ms)","trace[69996002] 'applied index is now lower than readState.Index' (duration: 205.135µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:42.074544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.673734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:42.07462Z","caller":"traceutil/trace.go:171","msg":"trace[1963268837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"342.768196ms","start":"2026-02-25T17:04:41.73184Z","end":"2026-02-25T17:04:42.074608Z","steps":["trace[1963268837] 'agreement among raft nodes before linearized reading' (duration: 342.645273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:42.074657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:41.731796Z","time spent":"342.853418ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:04:42.07452Z","caller":"traceutil/trace.go:171","msg":"trace[1037285171] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"571.455184ms","start":"2026-02-25T17:04:41.503039Z","end":"2026-02-25T17:04:42.074494Z","steps":["trace[1037285171] 'process raft request' (duration: 571.188148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:42.075036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:41.503016Z","time spent":"571.884293ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:42.521012Z","caller":"traceutil/trace.go:171","msg":"trace[117315089] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"442.094777ms","start":"2026-02-25T17:04:42.078884Z","end":"2026-02-25T17:04:42.520979Z","steps":["trace[117315089] 'process raft request' (duration: 377.040219ms)","trace[117315089] 'compare' (duration: 64.810363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:42.5212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:42.078859Z","time spent":"442.24457ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:42.521316Z","caller":"traceutil/trace.go:171","msg":"trace[1420480655] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:514; }","duration":"176.326792ms","start":"2026-02-25T17:04:42.344945Z","end":"2026-02-25T17:04:42.521272Z","steps":["trace[1420480655] 'read index received' (duration: 110.993137ms)","trace[1420480655] 'applied index is now lower than readState.Index' (duration: 65.331835ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:42.521636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.663709ms","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-25T17:04:42.521685Z","caller":"traceutil/trace.go:171","msg":"trace[1794841883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"176.789612ms","start":"2026-02-25T17:04:42.344882Z","end":"2026-02-25T17:04:42.521671Z","steps":["trace[1794841883] 'agreement among raft nodes before linearized reading' (duration: 176.561817ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:42.521775Z","caller":"traceutil/trace.go:171","msg":"trace[1095261491] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"442.412504ms","start":"2026-02-25T17:04:42.079347Z","end":"2026-02-25T17:04:42.521759Z","steps":["trace[1095261491] 'process raft request' (duration: 441.574435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:42.521856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:42.079327Z","time spent":"442.490586ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:43.738183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.697411ms","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-25T17:04:43.738246Z","caller":"traceutil/trace.go:171","msg":"trace[240347685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"778.800104ms","start":"2026-02-25T17:04:42.95943Z","end":"2026-02-25T17:04:43.73823Z","steps":["trace[240347685] 'range keys from in-memory index tree' (duration: 778.561318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:43.738275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:42.959411Z","time spent":"778.856995ms","remote":"127.0.0.1:55074","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-25T17:04:43.738302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004128775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:43.738396Z","caller":"traceutil/trace.go:171","msg":"trace[603761229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"1.004277488s","start":"2026-02-25T17:04:42.734096Z","end":"2026-02-25T17:04:43.738373Z","steps":["trace[603761229] 'range keys from in-memory index tree' (duration: 1.004027982s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:43.738441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:42.734075Z","time spent":"1.004353029s","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:44.249251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958675054970247520,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:04:44.557235Z","caller":"traceutil/trace.go:171","msg":"trace[939372072] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"814.985722ms","start":"2026-02-25T17:04:43.742232Z","end":"2026-02-25T17:04:44.557218Z","steps":["trace[939372072] 'process raft request' (duration: 814.899269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.557336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.74222Z","time spent":"815.065393ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:44.602695Z","caller":"traceutil/trace.go:171","msg":"trace[396443618] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"677.461177ms","start":"2026-02-25T17:04:43.925208Z","end":"2026-02-25T17:04:44.60267Z","steps":["trace[396443618] 'process raft request' (duration: 677.305044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.602814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.584666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:04:44.602836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.925182Z","time spent":"677.57947ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:44.60287Z","caller":"traceutil/trace.go:171","msg":"trace[713608688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"854.672688ms","start":"2026-02-25T17:04:43.748182Z","end":"2026-02-25T17:04:44.602855Z","steps":["trace[713608688] 'agreement among raft nodes before linearized reading' (duration: 854.584446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.602908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.74817Z","time spent":"854.731739ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:04:44.602938Z","caller":"traceutil/trace.go:171","msg":"trace[1130950312] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:517; }","duration":"854.426533ms","start":"2026-02-25T17:04:43.748214Z","end":"2026-02-25T17:04:44.602641Z","steps":["trace[1130950312] 'read index received' (duration: 808.976144ms)","trace[1130950312] 'applied index is now lower than readState.Index' (duration: 45.449539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:44.603028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.50482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T17:04:44.603052Z","caller":"traceutil/trace.go:171","msg":"trace[1078126059] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:501; }","duration":"744.559712ms","start":"2026-02-25T17:04:43.858485Z","end":"2026-02-25T17:04:44.603045Z","steps":["trace[1078126059] 'agreement among raft nodes before linearized reading' (duration: 744.518271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.603068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.858465Z","time spent":"744.598702ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.112\" "} {"level":"warn","ts":"2026-02-25T17:04:47.865027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064189748s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:47.865299Z","caller":"traceutil/trace.go:171","msg":"trace[2083213272] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.242850722s","start":"2026-02-25T17:04:46.62243Z","end":"2026-02-25T17:04:47.86528Z","steps":["trace[2083213272] 'process raft request' (duration: 1.24276446s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:47.86553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:46.622423Z","time spent":"1.243068257s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:47.865313Z","caller":"traceutil/trace.go:171","msg":"trace[1026001799] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.242920244s","start":"2026-02-25T17:04:46.62236Z","end":"2026-02-25T17:04:47.86528Z","steps":["trace[1026001799] 'process raft request' (duration: 177.871256ms)","trace[1026001799] 'compare' (duration: 1.063856451s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:47.865861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:46.622342Z","time spent":"1.243398465s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:48.206407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.473312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:48.206519Z","caller":"traceutil/trace.go:171","msg":"trace[1297168209] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.469538475s","start":"2026-02-25T17:04:46.736959Z","end":"2026-02-25T17:04:48.206498Z","steps":["trace[1297168209] 'process raft request' (duration: 1.23089824s)","trace[1297168209] 'compare' (duration: 238.297197ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:48.20659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:46.73694Z","time spent":"1.469619456s","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:48.620412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.808793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:48.620638Z","caller":"traceutil/trace.go:171","msg":"trace[883171195] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.682728108s","start":"2026-02-25T17:04:46.937861Z","end":"2026-02-25T17:04:48.62059Z","steps":["trace[883171195] 'process raft request' (duration: 1.400695041s)","trace[883171195] 'compare' (duration: 281.69868ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:48.620719Z","caller":"traceutil/trace.go:171","msg":"trace[2124212821] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.482510621s","start":"2026-02-25T17:04:47.138187Z","end":"2026-02-25T17:04:48.620697Z","steps":["trace[2124212821] 'process raft request' (duration: 1.482296016s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:48.620759Z","caller":"traceutil/trace.go:171","msg":"trace[1195839933] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:548; }","duration":"1.098681627s","start":"2026-02-25T17:04:47.522067Z","end":"2026-02-25T17:04:48.620749Z","steps":["trace[1195839933] 'read index received' (duration: 445.768521ms)","trace[1195839933] 'applied index is now lower than readState.Index' (duration: 652.912316ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:48.620808Z","caller":"traceutil/trace.go:171","msg":"trace[1794957885] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.282927349s","start":"2026-02-25T17:04:47.337874Z","end":"2026-02-25T17:04:48.620802Z","steps":["trace[1794957885] 'process raft request' (duration: 1.282646333s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.620794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.138148Z","time spent":"1.482612483s","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:48.620841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.337848Z","time spent":"1.28297474s","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:48.620838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:46.937841Z","time spent":"1.682906093s","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:48.62087Z","caller":"traceutil/trace.go:171","msg":"trace[1835640398] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"881.907502ms","start":"2026-02-25T17:04:47.738952Z","end":"2026-02-25T17:04:48.620859Z","steps":["trace[1835640398] 'process raft request' (duration: 881.668576ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:48.620899Z","caller":"traceutil/trace.go:171","msg":"trace[15119265] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"678.90772ms","start":"2026-02-25T17:04:47.941985Z","end":"2026-02-25T17:04:48.620893Z","steps":["trace[15119265] 'process raft request' (duration: 678.731016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.620917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.73893Z","time spent":"881.960892ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:48.620931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.941958Z","time spent":"678.958251ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:48.620985Z","caller":"traceutil/trace.go:171","msg":"trace[839772317] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.082824844s","start":"2026-02-25T17:04:47.538153Z","end":"2026-02-25T17:04:48.620978Z","steps":["trace[839772317] 'process raft request' (duration: 1.082440546s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.621016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.538135Z","time spent":"1.082863605s","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:48.621179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099086206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rlw7s\" ","response":"range_response_count:1 size:17171"} {"level":"info","ts":"2026-02-25T17:04:48.621287Z","caller":"traceutil/trace.go:171","msg":"trace[1848568245] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rlw7s; range_end:; response_count:1; response_revision:536; }","duration":"1.09923828s","start":"2026-02-25T17:04:47.522025Z","end":"2026-02-25T17:04:48.621264Z","steps":["trace[1848568245] 'agreement among raft nodes before linearized reading' (duration: 1.098903942s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.621345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.522003Z","time spent":"1.099330001s","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17194,"request content":"key:\"/registry/pods/kube-system/cilium-rlw7s\" "} {"level":"warn","ts":"2026-02-25T17:04:48.93557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.785784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:48.935839Z","caller":"traceutil/trace.go:171","msg":"trace[1541159867] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"796.901839ms","start":"2026-02-25T17:04:48.138898Z","end":"2026-02-25T17:04:48.9358Z","steps":["trace[1541159867] 'process raft request' (duration: 628.789886ms)","trace[1541159867] 'compare' (duration: 167.459527ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:48.935929Z","caller":"traceutil/trace.go:171","msg":"trace[1361814071] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"725.146377ms","start":"2026-02-25T17:04:48.21076Z","end":"2026-02-25T17:04:48.935907Z","steps":["trace[1361814071] 'process raft request' (duration: 724.943452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.936047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.138878Z","time spent":"797.061902ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:48.936103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.210744Z","time spent":"725.285861ms","remote":"127.0.0.1:55316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:48.93588Z","caller":"traceutil/trace.go:171","msg":"trace[762240399] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"725.024535ms","start":"2026-02-25T17:04:48.210832Z","end":"2026-02-25T17:04:48.935856Z","steps":["trace[762240399] 'process raft request' (duration: 724.942993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.936297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.210809Z","time spent":"725.420804ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:49.156488Z","caller":"traceutil/trace.go:171","msg":"trace[439674444] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:555; }","duration":"535.688536ms","start":"2026-02-25T17:04:48.620779Z","end":"2026-02-25T17:04:49.156467Z","steps":["trace[439674444] 'read index received' (duration: 147.040962ms)","trace[439674444] 'applied index is now lower than readState.Index' (duration: 388.646774ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:49.156628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423470252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:49.156659Z","caller":"traceutil/trace.go:171","msg":"trace[1226911346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"1.423518253s","start":"2026-02-25T17:04:47.733131Z","end":"2026-02-25T17:04:49.15665Z","steps":["trace[1226911346] 'agreement among raft nodes before linearized reading' (duration: 1.423445832s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.156702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.733076Z","time spent":"1.423615755s","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:49.156839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.556786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:49.15683Z","caller":"traceutil/trace.go:171","msg":"trace[1103545304] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"522.421153ms","start":"2026-02-25T17:04:48.63438Z","end":"2026-02-25T17:04:49.156801Z","steps":["trace[1103545304] 'process raft request' (duration: 522.004333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.156833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.786894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:49.156816Z","caller":"traceutil/trace.go:171","msg":"trace[491076617] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"626.85422ms","start":"2026-02-25T17:04:48.529881Z","end":"2026-02-25T17:04:49.156735Z","steps":["trace[491076617] 'process raft request' (duration: 565.279072ms)","trace[491076617] 'compare' (duration: 61.054486ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:49.156901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.961004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T17:04:49.156978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.634355Z","time spent":"522.539106ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17318,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:49.156882Z","caller":"traceutil/trace.go:171","msg":"trace[1440279105] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:541; }","duration":"620.615727ms","start":"2026-02-25T17:04:48.536253Z","end":"2026-02-25T17:04:49.156868Z","steps":["trace[1440279105] 'agreement among raft nodes before linearized reading' (duration: 620.556616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.157046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.536245Z","time spent":"620.789051ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-25T17:04:49.157083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.529858Z","time spent":"627.125256ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":743,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:49.157126Z","caller":"traceutil/trace.go:171","msg":"trace[697581599] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:541; }","duration":"603.321922ms","start":"2026-02-25T17:04:48.553695Z","end":"2026-02-25T17:04:49.157017Z","steps":["trace[697581599] 'agreement among raft nodes before linearized reading' (duration: 602.841172ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.157293Z","caller":"traceutil/trace.go:171","msg":"trace[650850129] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:541; }","duration":"821.129313ms","start":"2026-02-25T17:04:48.335937Z","end":"2026-02-25T17:04:49.157066Z","steps":["trace[650850129] 'agreement among raft nodes before linearized reading' (duration: 820.652661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.157243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.007613ms","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-25T17:04:49.157376Z","caller":"traceutil/trace.go:171","msg":"trace[542092426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"588.238778ms","start":"2026-02-25T17:04:48.569121Z","end":"2026-02-25T17:04:49.157359Z","steps":["trace[542092426] 'agreement among raft nodes before linearized reading' (duration: 587.729786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.157409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.553679Z","time spent":"603.60931ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:04:49.157334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.335912Z","time spent":"821.410389ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-25T17:04:49.158654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.569102Z","time spent":"589.533147ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T17:04:49.20042Z","caller":"traceutil/trace.go:171","msg":"trace[1198362241] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"252.686997ms","start":"2026-02-25T17:04:48.947679Z","end":"2026-02-25T17:04:49.200366Z","steps":["trace[1198362241] 'process raft request' (duration: 252.572984ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.200549Z","caller":"traceutil/trace.go:171","msg":"trace[1325806774] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"252.925382ms","start":"2026-02-25T17:04:48.947595Z","end":"2026-02-25T17:04:49.20052Z","steps":["trace[1325806774] 'process raft request' (duration: 252.420711ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.27163Z","caller":"traceutil/trace.go:171","msg":"trace[563738227] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"108.982511ms","start":"2026-02-25T17:04:49.162631Z","end":"2026-02-25T17:04:49.271613Z","steps":["trace[563738227] 'process raft request' (duration: 108.92733ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.271637Z","caller":"traceutil/trace.go:171","msg":"trace[511640502] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"109.662687ms","start":"2026-02-25T17:04:49.161936Z","end":"2026-02-25T17:04:49.271598Z","steps":["trace[511640502] 'process raft request' (duration: 109.516504ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.271655Z","caller":"traceutil/trace.go:171","msg":"trace[1337392102] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:560; }","duration":"115.06068ms","start":"2026-02-25T17:04:49.156554Z","end":"2026-02-25T17:04:49.271615Z","steps":["trace[1337392102] 'read index received' (duration: 43.425083ms)","trace[1337392102] 'applied index is now lower than readState.Index' (duration: 71.633787ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:49.271897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.081822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-25T17:04:49.271962Z","caller":"traceutil/trace.go:171","msg":"trace[912583887] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:546; }","duration":"535.197776ms","start":"2026-02-25T17:04:48.736752Z","end":"2026-02-25T17:04:49.27195Z","steps":["trace[912583887] 'agreement among raft nodes before linearized reading' (duration: 535.052752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.272027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.736727Z","time spent":"535.286898ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-25T17:04:49.272095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.3352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-25T17:04:49.272123Z","caller":"traceutil/trace.go:171","msg":"trace[1441613366] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:547; }","duration":"336.463533ms","start":"2026-02-25T17:04:48.935651Z","end":"2026-02-25T17:04:49.272114Z","steps":["trace[1441613366] 'agreement among raft nodes before linearized reading' (duration: 336.097873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.27214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.93563Z","time spent":"336.504994ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-25T17:04:49.272214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.383129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:3217"} {"level":"info","ts":"2026-02-25T17:04:49.272231Z","caller":"traceutil/trace.go:171","msg":"trace[1981172795] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:547; }","duration":"136.435079ms","start":"2026-02-25T17:04:49.135791Z","end":"2026-02-25T17:04:49.272226Z","steps":["trace[1981172795] 'agreement among raft nodes before linearized reading' (duration: 135.965538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.272276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.45853ms","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-25T17:04:49.27232Z","caller":"traceutil/trace.go:171","msg":"trace[681350958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"643.527792ms","start":"2026-02-25T17:04:48.628784Z","end":"2026-02-25T17:04:49.272312Z","steps":["trace[681350958] 'agreement among raft nodes before linearized reading' (duration: 643.405609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.272339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.530524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:49.272356Z","caller":"traceutil/trace.go:171","msg":"trace[548257338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"112.598315ms","start":"2026-02-25T17:04:49.159753Z","end":"2026-02-25T17:04:49.272352Z","steps":["trace[548257338] 'agreement among raft nodes before linearized reading' (duration: 112.573854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.272477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.628769Z","time spent":"643.580083ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T17:04:49.272538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.52346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rlw7s\" ","response":"range_response_count:1 size:17333"} {"level":"info","ts":"2026-02-25T17:04:49.272588Z","caller":"traceutil/trace.go:171","msg":"trace[555960350] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rlw7s; range_end:; response_count:1; response_revision:547; }","duration":"111.666713ms","start":"2026-02-25T17:04:49.160908Z","end":"2026-02-25T17:04:49.272574Z","steps":["trace[555960350] 'agreement among raft nodes before linearized reading' (duration: 111.489099ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.628806Z","caller":"traceutil/trace.go:171","msg":"trace[25645198] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"349.363977ms","start":"2026-02-25T17:04:49.279418Z","end":"2026-02-25T17:04:49.628782Z","steps":["trace[25645198] 'read index received' (duration: 80.668664ms)","trace[25645198] 'applied index is now lower than readState.Index' (duration: 268.694413ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:49.628951Z","caller":"traceutil/trace.go:171","msg":"trace[246199825] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"351.016655ms","start":"2026-02-25T17:04:49.277885Z","end":"2026-02-25T17:04:49.628901Z","steps":["trace[246199825] 'process raft request' (duration: 350.626436ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.629051Z","caller":"traceutil/trace.go:171","msg":"trace[117217584] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"345.267752ms","start":"2026-02-25T17:04:49.283755Z","end":"2026-02-25T17:04:49.629023Z","steps":["trace[117217584] 'process raft request' (duration: 344.973536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.629165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.277867Z","time spent":"351.185318ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:49.629259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.283743Z","time spent":"345.463998ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17400,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:49.628881Z","caller":"traceutil/trace.go:171","msg":"trace[125458360] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"350.892251ms","start":"2026-02-25T17:04:49.277946Z","end":"2026-02-25T17:04:49.628838Z","steps":["trace[125458360] 'process raft request' (duration: 350.729668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.629487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.076624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:49.629522Z","caller":"traceutil/trace.go:171","msg":"trace[546895316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"350.142294ms","start":"2026-02-25T17:04:49.279368Z","end":"2026-02-25T17:04:49.62951Z","steps":["trace[546895316] 'agreement among raft nodes before linearized reading' (duration: 350.086913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.629548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.279352Z","time spent":"350.189576ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:49.629772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.462539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:9358"} {"level":"info","ts":"2026-02-25T17:04:49.629841Z","caller":"traceutil/trace.go:171","msg":"trace[749012690] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:551; }","duration":"293.574191ms","start":"2026-02-25T17:04:49.33625Z","end":"2026-02-25T17:04:49.629824Z","steps":["trace[749012690] 'agreement among raft nodes before linearized reading' (duration: 292.812834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.629863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.277918Z","time spent":"351.479405ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:49.814751Z","caller":"traceutil/trace.go:171","msg":"trace[803416531] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"176.332171ms","start":"2026-02-25T17:04:49.638395Z","end":"2026-02-25T17:04:49.814727Z","steps":["trace[803416531] 'process raft request' (duration: 176.216128ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.893123Z","caller":"traceutil/trace.go:171","msg":"trace[1279382693] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"252.576324ms","start":"2026-02-25T17:04:49.640519Z","end":"2026-02-25T17:04:49.893096Z","steps":["trace[1279382693] 'process raft request' (duration: 237.218354ms)","trace[1279382693] 'compare' (duration: 15.087374ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:49.893218Z","caller":"traceutil/trace.go:171","msg":"trace[104948782] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"252.520952ms","start":"2026-02-25T17:04:49.640663Z","end":"2026-02-25T17:04:49.893184Z","steps":["trace[104948782] 'process raft request' (duration: 252.327208ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.893276Z","caller":"traceutil/trace.go:171","msg":"trace[245158542] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"232.520095ms","start":"2026-02-25T17:04:49.660748Z","end":"2026-02-25T17:04:49.893269Z","steps":["trace[245158542] 'process raft request' (duration: 232.30457ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:49.893269Z","caller":"traceutil/trace.go:171","msg":"trace[127315095] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:571; }","duration":"156.690032ms","start":"2026-02-25T17:04:49.736406Z","end":"2026-02-25T17:04:49.893096Z","steps":["trace[127315095] 'read index received' (duration: 78.2783ms)","trace[127315095] 'applied index is now lower than readState.Index' (duration: 78.410712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:49.893574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.149643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-25T17:04:49.893622Z","caller":"traceutil/trace.go:171","msg":"trace[676179256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"157.239195ms","start":"2026-02-25T17:04:49.736367Z","end":"2026-02-25T17:04:49.893606Z","steps":["trace[676179256] 'agreement among raft nodes before linearized reading' (duration: 156.732333ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:50.363362Z","caller":"traceutil/trace.go:171","msg":"trace[478334104] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:577; }","duration":"427.168476ms","start":"2026-02-25T17:04:49.936172Z","end":"2026-02-25T17:04:50.363341Z","steps":["trace[478334104] 'read index received' (duration: 7.224145ms)","trace[478334104] 'applied index is now lower than readState.Index' (duration: 419.943331ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:50.3634Z","caller":"traceutil/trace.go:171","msg":"trace[1443814310] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"460.835964ms","start":"2026-02-25T17:04:49.902537Z","end":"2026-02-25T17:04:50.363373Z","steps":["trace[1443814310] 'process raft request' (duration: 390.602899ms)","trace[1443814310] 'compare' (duration: 69.929438ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:50.363538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.35397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-25T17:04:50.36357Z","caller":"traceutil/trace.go:171","msg":"trace[823961825] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:561; }","duration":"427.428801ms","start":"2026-02-25T17:04:49.936133Z","end":"2026-02-25T17:04:50.363562Z","steps":["trace[823961825] 'agreement among raft nodes before linearized reading' (duration: 427.312768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:50.363594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.936115Z","time spent":"427.472362ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-25T17:04:50.363572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.902519Z","time spent":"460.933587ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:50.523728Z","caller":"traceutil/trace.go:171","msg":"trace[112387086] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"570.809578ms","start":"2026-02-25T17:04:49.952899Z","end":"2026-02-25T17:04:50.523708Z","steps":["trace[112387086] 'process raft request' (duration: 570.682425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:50.523863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:49.95288Z","time spent":"570.91097ms","remote":"127.0.0.1:55242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:50.907496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.580645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:50.907765Z","caller":"traceutil/trace.go:171","msg":"trace[1770723003] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"545.407268ms","start":"2026-02-25T17:04:50.362301Z","end":"2026-02-25T17:04:50.907708Z","steps":["trace[1770723003] 'process raft request' (duration: 295.534896ms)","trace[1770723003] 'compare' (duration: 249.150125ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:50.907919Z","caller":"traceutil/trace.go:171","msg":"trace[1087420375] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"539.766038ms","start":"2026-02-25T17:04:50.368096Z","end":"2026-02-25T17:04:50.907862Z","steps":["trace[1087420375] 'process raft request' (duration: 539.511862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:50.908144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.368076Z","time spent":"539.950804ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:50.908182Z","caller":"traceutil/trace.go:171","msg":"trace[1543565418] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"544.737844ms","start":"2026-02-25T17:04:50.363423Z","end":"2026-02-25T17:04:50.908161Z","steps":["trace[1543565418] 'read index received' (duration: 160.976441ms)","trace[1543565418] 'applied index is now lower than readState.Index' (duration: 383.760353ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:50.907919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.362276Z","time spent":"545.573021ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:50.908225Z","caller":"traceutil/trace.go:171","msg":"trace[1196859843] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"534.054279ms","start":"2026-02-25T17:04:50.374157Z","end":"2026-02-25T17:04:50.908211Z","steps":["trace[1196859843] 'process raft request' (duration: 533.582397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:50.908405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.374144Z","time spent":"534.205142ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:50.908563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.61236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24235"} {"level":"info","ts":"2026-02-25T17:04:50.908618Z","caller":"traceutil/trace.go:171","msg":"trace[665361246] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:565; }","duration":"771.849085ms","start":"2026-02-25T17:04:50.136754Z","end":"2026-02-25T17:04:50.908603Z","steps":["trace[665361246] 'agreement among raft nodes before linearized reading' (duration: 771.498927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:50.908692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.136732Z","time spent":"771.947428ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24258,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-02-25T17:04:50.94742Z","caller":"traceutil/trace.go:171","msg":"trace[1877424158] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"377.718325ms","start":"2026-02-25T17:04:50.569686Z","end":"2026-02-25T17:04:50.947404Z","steps":["trace[1877424158] 'process raft request' (duration: 377.578672ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:50.947422Z","caller":"traceutil/trace.go:171","msg":"trace[663976332] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"407.175309ms","start":"2026-02-25T17:04:50.540209Z","end":"2026-02-25T17:04:50.947385Z","steps":["trace[663976332] 'process raft request' (duration: 406.795449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:50.947542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.569659Z","time spent":"377.809837ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:50.947553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.540185Z","time spent":"407.304762ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:50.947708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.618737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:50.947733Z","caller":"traceutil/trace.go:171","msg":"trace[465292108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"214.678228ms","start":"2026-02-25T17:04:50.733049Z","end":"2026-02-25T17:04:50.947728Z","steps":["trace[465292108] 'agreement among raft nodes before linearized reading' (duration: 214.638127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:50.947952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.452223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rlw7s\" ","response":"range_response_count:1 size:17415"} {"level":"info","ts":"2026-02-25T17:04:50.947999Z","caller":"traceutil/trace.go:171","msg":"trace[422676682] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rlw7s; range_end:; response_count:1; response_revision:567; }","duration":"410.501574ms","start":"2026-02-25T17:04:50.537486Z","end":"2026-02-25T17:04:50.947988Z","steps":["trace[422676682] 'agreement among raft nodes before linearized reading' (duration: 410.351931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:50.948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.554619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:12259"} {"level":"warn","ts":"2026-02-25T17:04:50.94803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.537436Z","time spent":"410.585566ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17438,"request content":"key:\"/registry/pods/kube-system/cilium-rlw7s\" "} {"level":"info","ts":"2026-02-25T17:04:50.948044Z","caller":"traceutil/trace.go:171","msg":"trace[152708194] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:567; }","duration":"212.61536ms","start":"2026-02-25T17:04:50.73542Z","end":"2026-02-25T17:04:50.948035Z","steps":["trace[152708194] 'agreement among raft nodes before linearized reading' (duration: 212.217991ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.28305Z","caller":"traceutil/trace.go:171","msg":"trace[1320783523] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"335.628462ms","start":"2026-02-25T17:04:50.947384Z","end":"2026-02-25T17:04:51.283013Z","steps":["trace[1320783523] 'read index received' (duration: 293.481868ms)","trace[1320783523] 'applied index is now lower than readState.Index' (duration: 42.145604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:51.283167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.639044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-25T17:04:51.2832Z","caller":"traceutil/trace.go:171","msg":"trace[1389095046] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:568; }","duration":"346.704035ms","start":"2026-02-25T17:04:50.93649Z","end":"2026-02-25T17:04:51.283194Z","steps":["trace[1389095046] 'agreement among raft nodes before linearized reading' (duration: 346.593702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.283222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.936476Z","time spent":"346.738706ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-02-25T17:04:51.283045Z","caller":"traceutil/trace.go:171","msg":"trace[1493967690] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"361.845812ms","start":"2026-02-25T17:04:50.921182Z","end":"2026-02-25T17:04:51.283028Z","steps":["trace[1493967690] 'process raft request' (duration: 319.572906ms)","trace[1493967690] 'compare' (duration: 42.133663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:51.283373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.921161Z","time spent":"362.15196ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:51.31927Z","caller":"traceutil/trace.go:171","msg":"trace[1613855243] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"366.487218ms","start":"2026-02-25T17:04:50.952731Z","end":"2026-02-25T17:04:51.319218Z","steps":["trace[1613855243] 'process raft request' (duration: 366.294503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.320546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.952715Z","time spent":"367.752467ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:51.321801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.108689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:51.321846Z","caller":"traceutil/trace.go:171","msg":"trace[1530016865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"373.16562ms","start":"2026-02-25T17:04:50.948668Z","end":"2026-02-25T17:04:51.321833Z","steps":["trace[1530016865] 'agreement among raft nodes before linearized reading' (duration: 372.469525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.321878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.948652Z","time spent":"373.214432ms","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:04:51.323138Z","caller":"traceutil/trace.go:171","msg":"trace[1069753472] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"361.514914ms","start":"2026-02-25T17:04:50.961604Z","end":"2026-02-25T17:04:51.323119Z","steps":["trace[1069753472] 'process raft request' (duration: 357.556913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.323213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:50.961578Z","time spent":"361.598587ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17655,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:51.323419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.310032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-25T17:04:51.323455Z","caller":"traceutil/trace.go:171","msg":"trace[208602722] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:570; }","duration":"187.465106ms","start":"2026-02-25T17:04:51.135975Z","end":"2026-02-25T17:04:51.323441Z","steps":["trace[208602722] 'agreement among raft nodes before linearized reading' (duration: 187.283382ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.477803Z","caller":"traceutil/trace.go:171","msg":"trace[643479439] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"139.440438ms","start":"2026-02-25T17:04:51.338348Z","end":"2026-02-25T17:04:51.477788Z","steps":["trace[643479439] 'read index received' (duration: 11.593995ms)","trace[643479439] 'applied index is now lower than readState.Index' (duration: 127.845543ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:51.477795Z","caller":"traceutil/trace.go:171","msg":"trace[1540877914] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"140.345248ms","start":"2026-02-25T17:04:51.337413Z","end":"2026-02-25T17:04:51.477758Z","steps":["trace[1540877914] 'process raft request' (duration: 72.884406ms)","trace[1540877914] 'compare' (duration: 67.126215ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:51.477937Z","caller":"traceutil/trace.go:171","msg":"trace[1562841151] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"135.731372ms","start":"2026-02-25T17:04:51.34215Z","end":"2026-02-25T17:04:51.477882Z","steps":["trace[1562841151] 'process raft request' (duration: 135.439736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.477995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.624162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"warn","ts":"2026-02-25T17:04:51.478044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.981058ms","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-25T17:04:51.478059Z","caller":"traceutil/trace.go:171","msg":"trace[917662065] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"139.718215ms","start":"2026-02-25T17:04:51.338322Z","end":"2026-02-25T17:04:51.47804Z","steps":["trace[917662065] 'agreement among raft nodes before linearized reading' (duration: 139.536241ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:51.478101Z","caller":"traceutil/trace.go:171","msg":"trace[1190374980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"115.074011ms","start":"2026-02-25T17:04:51.363013Z","end":"2026-02-25T17:04:51.478087Z","steps":["trace[1190374980] 'agreement among raft nodes before linearized reading' (duration: 114.920697ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:58.084882Z","caller":"traceutil/trace.go:171","msg":"trace[1322434507] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"144.004821ms","start":"2026-02-25T17:04:57.94084Z","end":"2026-02-25T17:04:58.084844Z","steps":["trace[1322434507] 'process raft request' (duration: 143.842538ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:58.340377Z","caller":"traceutil/trace.go:171","msg":"trace[1467720742] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"397.377744ms","start":"2026-02-25T17:04:57.942981Z","end":"2026-02-25T17:04:58.340358Z","steps":["trace[1467720742] 'process raft request' (duration: 396.865212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:58.340481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:57.942959Z","time spent":"397.469796ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:58.463502Z","caller":"traceutil/trace.go:171","msg":"trace[1324727582] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"109.949004ms","start":"2026-02-25T17:04:58.353514Z","end":"2026-02-25T17:04:58.463463Z","steps":["trace[1324727582] 'process raft request' (duration: 106.184777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.471939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.474064ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:05:00.472173Z","caller":"traceutil/trace.go:171","msg":"trace[2137172884] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"382.603507ms","start":"2026-02-25T17:05:00.08954Z","end":"2026-02-25T17:05:00.472144Z","steps":["trace[2137172884] 'read index received' (duration: 33.821µs)","trace[2137172884] 'applied index is now lower than readState.Index' (duration: 382.568126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:00.472677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.928857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:00.472749Z","caller":"traceutil/trace.go:171","msg":"trace[1055477404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"269.03781ms","start":"2026-02-25T17:05:00.203673Z","end":"2026-02-25T17:05:00.47271Z","steps":["trace[1055477404] 'agreement among raft nodes before linearized reading' (duration: 268.930148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.472968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.14732ms","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-25T17:05:00.47304Z","caller":"traceutil/trace.go:171","msg":"trace[1110637669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"383.513897ms","start":"2026-02-25T17:05:00.089508Z","end":"2026-02-25T17:05:00.473021Z","steps":["trace[1110637669] 'agreement among raft nodes before linearized reading' (duration: 383.050167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.473027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.344977ms","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-25T17:05:00.473093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:00.089491Z","time spent":"383.58741ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:05:00.473103Z","caller":"traceutil/trace.go:171","msg":"trace[1764648754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"129.432929ms","start":"2026-02-25T17:05:00.34365Z","end":"2026-02-25T17:05:00.473083Z","steps":["trace[1764648754] 'agreement among raft nodes before linearized reading' (duration: 129.198943ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:00.608892Z","caller":"traceutil/trace.go:171","msg":"trace[1898451827] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"131.616188ms","start":"2026-02-25T17:05:00.477259Z","end":"2026-02-25T17:05:00.608875Z","steps":["trace[1898451827] 'process raft request' (duration: 131.449765ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:00.617226Z","caller":"traceutil/trace.go:171","msg":"trace[1305882726] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"139.382046ms","start":"2026-02-25T17:05:00.477816Z","end":"2026-02-25T17:05:00.617198Z","steps":["trace[1305882726] 'process raft request' (duration: 139.198192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:04.017505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958675054970247853,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:05:04.115099Z","caller":"traceutil/trace.go:171","msg":"trace[511799215] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"635.041498ms","start":"2026-02-25T17:05:03.479985Z","end":"2026-02-25T17:05:04.115026Z","steps":["trace[511799215] 'process raft request' (duration: 634.931455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:04.115267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:03.479961Z","time spent":"635.193422ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:04.841492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.883837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:05:04.841601Z","caller":"traceutil/trace.go:171","msg":"trace[73286234] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:705; }","duration":"1.324361816s","start":"2026-02-25T17:05:03.517224Z","end":"2026-02-25T17:05:04.841586Z","steps":["trace[73286234] 'read index received' (duration: 597.696733ms)","trace[73286234] 'applied index is now lower than readState.Index' (duration: 726.663683ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:04.841701Z","caller":"traceutil/trace.go:171","msg":"trace[1074665339] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.24141623s","start":"2026-02-25T17:05:03.600253Z","end":"2026-02-25T17:05:04.841669Z","steps":["trace[1074665339] 'process raft request' (duration: 846.267037ms)","trace[1074665339] 'compare' (duration: 394.418167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:04.841802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324583811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T17:05:04.841841Z","caller":"traceutil/trace.go:171","msg":"trace[1621235348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.324680033s","start":"2026-02-25T17:05:03.517151Z","end":"2026-02-25T17:05:04.841831Z","steps":["trace[1621235348] 'agreement among raft nodes before linearized reading' (duration: 1.3245207s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:04.841893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:03.517125Z","time spent":"1.324758364s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:05:04.841898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:03.600216Z","time spent":"1.241563483s","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:05:04.841962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241840461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-l8p5g\" ","response":"range_response_count:1 size:4818"} {"level":"info","ts":"2026-02-25T17:05:04.842023Z","caller":"traceutil/trace.go:171","msg":"trace[1527474110] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-l8p5g; range_end:; response_count:1; response_revision:683; }","duration":"1.241946213s","start":"2026-02-25T17:05:03.60006Z","end":"2026-02-25T17:05:04.842006Z","steps":["trace[1527474110] 'agreement among raft nodes before linearized reading' (duration: 1.241777108s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:04.842059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:03.600036Z","time spent":"1.242015934s","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4841,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-l8p5g\" "} {"level":"warn","ts":"2026-02-25T17:05:04.842068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.423892ms","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-25T17:05:04.841966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.54772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-25T17:05:04.842207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162350513s","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-25T17:05:04.842128Z","caller":"traceutil/trace.go:171","msg":"trace[289864651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"172.520304ms","start":"2026-02-25T17:05:04.669591Z","end":"2026-02-25T17:05:04.842111Z","steps":["trace[289864651] 'agreement among raft nodes before linearized reading' (duration: 172.407692ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:04.842262Z","caller":"traceutil/trace.go:171","msg":"trace[1491512484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"1.162443365s","start":"2026-02-25T17:05:03.679807Z","end":"2026-02-25T17:05:04.84225Z","steps":["trace[1491512484] 'agreement among raft nodes before linearized reading' (duration: 1.162313642s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:04.842133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.283169ms","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-25T17:05:04.842301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:03.679751Z","time spent":"1.162542348s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T17:05:04.842325Z","caller":"traceutil/trace.go:171","msg":"trace[1442239813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:683; }","duration":"207.507934ms","start":"2026-02-25T17:05:04.634807Z","end":"2026-02-25T17:05:04.842315Z","steps":["trace[1442239813] 'agreement among raft nodes before linearized reading' (duration: 207.261488ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:04.842207Z","caller":"traceutil/trace.go:171","msg":"trace[1218800133] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:683; }","duration":"981.817816ms","start":"2026-02-25T17:05:03.860365Z","end":"2026-02-25T17:05:04.842183Z","steps":["trace[1218800133] 'agreement among raft nodes before linearized reading' (duration: 981.478967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:04.842476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:03.860347Z","time spent":"982.081283ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.112\" "} {"level":"warn","ts":"2026-02-25T17:05:04.842792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109382772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:04.842858Z","caller":"traceutil/trace.go:171","msg":"trace[1470747306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"1.109487435s","start":"2026-02-25T17:05:03.733353Z","end":"2026-02-25T17:05:04.84284Z","steps":["trace[1470747306] 'agreement among raft nodes before linearized reading' (duration: 1.108702997s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:04.843026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:03.733333Z","time spent":"1.109647868s","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:05:05.483419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.46022ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-25T17:05:05.48352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.845147Z","time spent":"638.369664ms","remote":"127.0.0.1:54852","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T17:05:05.534434Z","caller":"traceutil/trace.go:171","msg":"trace[1057423951] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"688.181133ms","start":"2026-02-25T17:05:04.846217Z","end":"2026-02-25T17:05:05.534398Z","steps":["trace[1057423951] 'process raft request' (duration: 687.652631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.534607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.846207Z","time spent":"688.310755ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:05.534706Z","caller":"traceutil/trace.go:171","msg":"trace[1655141659] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"688.428198ms","start":"2026-02-25T17:05:04.84625Z","end":"2026-02-25T17:05:05.534678Z","steps":["trace[1655141659] 'process raft request' (duration: 687.959137ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.534799Z","caller":"traceutil/trace.go:171","msg":"trace[822747145] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"688.06308ms","start":"2026-02-25T17:05:04.846725Z","end":"2026-02-25T17:05:05.534789Z","steps":["trace[822747145] 'process raft request' (duration: 687.856796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.53485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.846713Z","time spent":"688.112461ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:05.534859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.846234Z","time spent":"688.539551ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:05.53556Z","caller":"traceutil/trace.go:171","msg":"trace[1743743499] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"687.697111ms","start":"2026-02-25T17:05:04.847853Z","end":"2026-02-25T17:05:05.535551Z","steps":["trace[1743743499] 'process raft request' (duration: 686.77695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.535613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.847846Z","time spent":"687.740851ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:05.5356Z","caller":"traceutil/trace.go:171","msg":"trace[850681777] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"500.01672ms","start":"2026-02-25T17:05:05.035564Z","end":"2026-02-25T17:05:05.535581Z","steps":["trace[850681777] 'process raft request' (duration: 499.605061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.535706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.035542Z","time spent":"500.109633ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:05.53584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.931945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:05.535902Z","caller":"traceutil/trace.go:171","msg":"trace[589742526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"684.019617ms","start":"2026-02-25T17:05:04.851874Z","end":"2026-02-25T17:05:05.535894Z","steps":["trace[589742526] 'agreement among raft nodes before linearized reading' (duration: 683.936795ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.535904Z","caller":"traceutil/trace.go:171","msg":"trace[699882332] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:707; }","duration":"683.981436ms","start":"2026-02-25T17:05:04.851905Z","end":"2026-02-25T17:05:05.535548Z","steps":["trace[699882332] 'read index received' (duration: 264.058546ms)","trace[699882332] 'applied index is now lower than readState.Index' (duration: 419.583513ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:05.535927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.851861Z","time spent":"684.057238ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:05:09.06321Z","caller":"traceutil/trace.go:171","msg":"trace[1269445649] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"136.305606ms","start":"2026-02-25T17:05:08.926881Z","end":"2026-02-25T17:05:09.063187Z","steps":["trace[1269445649] 'process raft request' (duration: 120.2759ms)","trace[1269445649] 'compare' (duration: 15.886523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:09.111062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.481116ms","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":"warn","ts":"2026-02-25T17:05:09.111117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.137797ms","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-25T17:05:09.111133Z","caller":"traceutil/trace.go:171","msg":"trace[2095640744] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:698; }","duration":"135.579088ms","start":"2026-02-25T17:05:08.975538Z","end":"2026-02-25T17:05:09.111118Z","steps":["trace[2095640744] 'agreement among raft nodes before linearized reading' (duration: 135.449616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:09.111144Z","caller":"traceutil/trace.go:171","msg":"trace[102799215] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:698; }","duration":"103.171128ms","start":"2026-02-25T17:05:09.007964Z","end":"2026-02-25T17:05:09.111135Z","steps":["trace[102799215] 'agreement among raft nodes before linearized reading' (duration: 103.134157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:09.111305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.825594ms","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":"warn","ts":"2026-02-25T17:05:09.11106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.478696ms","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":"info","ts":"2026-02-25T17:05:09.111334Z","caller":"traceutil/trace.go:171","msg":"trace[330327040] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:698; }","duration":"103.853785ms","start":"2026-02-25T17:05:09.007471Z","end":"2026-02-25T17:05:09.111325Z","steps":["trace[330327040] 'agreement among raft nodes before linearized reading' (duration: 103.818354ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:09.111344Z","caller":"traceutil/trace.go:171","msg":"trace[1368953581] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:698; }","duration":"103.772743ms","start":"2026-02-25T17:05:09.007565Z","end":"2026-02-25T17:05:09.111338Z","steps":["trace[1368953581] 'agreement among raft nodes before linearized reading' (duration: 103.452505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:09.111331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.57997ms","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":"info","ts":"2026-02-25T17:05:09.111489Z","caller":"traceutil/trace.go:171","msg":"trace[537506041] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:698; }","duration":"135.759584ms","start":"2026-02-25T17:05:08.975692Z","end":"2026-02-25T17:05:09.111451Z","steps":["trace[537506041] 'agreement among raft nodes before linearized reading' (duration: 135.59439ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:19.781848Z","caller":"traceutil/trace.go:171","msg":"trace[165889132] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"126.094303ms","start":"2026-02-25T17:05:19.655725Z","end":"2026-02-25T17:05:19.781819Z","steps":["trace[165889132] 'process raft request' (duration: 77.065981ms)","trace[165889132] 'compare' (duration: 48.826836ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:19.781842Z","caller":"traceutil/trace.go:171","msg":"trace[1730273438] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"124.877796ms","start":"2026-02-25T17:05:19.656944Z","end":"2026-02-25T17:05:19.781821Z","steps":["trace[1730273438] 'process raft request' (duration: 124.802314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:20.806294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.565218ms","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-25T17:05:20.806427Z","caller":"traceutil/trace.go:171","msg":"trace[1121274374] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:736; }","duration":"372.717311ms","start":"2026-02-25T17:05:20.433688Z","end":"2026-02-25T17:05:20.806405Z","steps":["trace[1121274374] 'range keys from in-memory index tree' (duration: 372.544957ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:20.844334Z","caller":"traceutil/trace.go:171","msg":"trace[1685481987] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:764; }","duration":"200.951514ms","start":"2026-02-25T17:05:20.643363Z","end":"2026-02-25T17:05:20.844315Z","steps":["trace[1685481987] 'read index received' (duration: 191.207821ms)","trace[1685481987] 'applied index is now lower than readState.Index' (duration: 9.742073ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:20.844459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.362964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:05:20.844464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.089807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:05:20.844482Z","caller":"traceutil/trace.go:171","msg":"trace[235964848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:736; }","duration":"110.401735ms","start":"2026-02-25T17:05:20.734072Z","end":"2026-02-25T17:05:20.844474Z","steps":["trace[235964848] 'agreement among raft nodes before linearized reading' (duration: 110.346704ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:20.844505Z","caller":"traceutil/trace.go:171","msg":"trace[1958616074] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:736; }","duration":"201.184869ms","start":"2026-02-25T17:05:20.643309Z","end":"2026-02-25T17:05:20.844494Z","steps":["trace[1958616074] 'agreement among raft nodes before linearized reading' (duration: 201.093067ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:26.062453Z","caller":"traceutil/trace.go:171","msg":"trace[1417878372] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"160.810816ms","start":"2026-02-25T17:05:25.901598Z","end":"2026-02-25T17:05:26.062409Z","steps":["trace[1417878372] 'process raft request' (duration: 159.609318ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:34.994333Z","caller":"traceutil/trace.go:171","msg":"trace[1409482195] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"260.894973ms","start":"2026-02-25T17:05:34.73341Z","end":"2026-02-25T17:05:34.994305Z","steps":["trace[1409482195] 'read index received' (duration: 260.700929ms)","trace[1409482195] 'applied index is now lower than readState.Index' (duration: 192.864µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:34.994474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.047556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:34.994522Z","caller":"traceutil/trace.go:171","msg":"trace[702716686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"261.149539ms","start":"2026-02-25T17:05:34.733359Z","end":"2026-02-25T17:05:34.994509Z","steps":["trace[702716686] 'agreement among raft nodes before linearized reading' (duration: 261.059777ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:34.994464Z","caller":"traceutil/trace.go:171","msg":"trace[617600729] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"604.775981ms","start":"2026-02-25T17:05:34.389667Z","end":"2026-02-25T17:05:34.994443Z","steps":["trace[617600729] 'process raft request' (duration: 604.522006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:34.994852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:34.389641Z","time spent":"604.985496ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:36.465013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958675054970248122,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:05:36.831819Z","caller":"traceutil/trace.go:171","msg":"trace[621061132] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"872.131195ms","start":"2026-02-25T17:05:35.959668Z","end":"2026-02-25T17:05:36.831799Z","steps":["trace[621061132] 'process raft request' (duration: 871.995882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:36.832113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:35.959648Z","time spent":"872.236098ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:37.012611Z","caller":"traceutil/trace.go:171","msg":"trace[487428739] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"1.047790071s","start":"2026-02-25T17:05:35.96479Z","end":"2026-02-25T17:05:37.01258Z","steps":["trace[487428739] 'read index received' (duration: 867.082847ms)","trace[487428739] 'applied index is now lower than readState.Index' (duration: 180.705884ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:37.012764Z","caller":"traceutil/trace.go:171","msg":"trace[971682236] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"715.311022ms","start":"2026-02-25T17:05:36.297428Z","end":"2026-02-25T17:05:37.012739Z","steps":["trace[971682236] 'process raft request' (duration: 663.709356ms)","trace[971682236] 'compare' (duration: 51.294629ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:37.012903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.900149ms","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-25T17:05:37.012966Z","caller":"traceutil/trace.go:171","msg":"trace[589255446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"908.994753ms","start":"2026-02-25T17:05:36.103959Z","end":"2026-02-25T17:05:37.012954Z","steps":["trace[589255446] 'agreement among raft nodes before linearized reading' (duration: 908.818208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:37.012979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.929587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:05:37.012992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:36.103938Z","time spent":"909.046244ms","remote":"127.0.0.1:55074","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-25T17:05:37.013014Z","caller":"traceutil/trace.go:171","msg":"trace[627989793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"278.992539ms","start":"2026-02-25T17:05:36.734009Z","end":"2026-02-25T17:05:37.013001Z","steps":["trace[627989793] 'agreement among raft nodes before linearized reading' (duration: 278.939788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:37.0129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:36.297404Z","time spent":"715.408534ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:37.013487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048670562s","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-25T17:05:37.013571Z","caller":"traceutil/trace.go:171","msg":"trace[1324305659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"1.048797216s","start":"2026-02-25T17:05:35.964754Z","end":"2026-02-25T17:05:37.013552Z","steps":["trace[1324305659] 'agreement among raft nodes before linearized reading' (duration: 1.04852456s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:37.013619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:35.964743Z","time spent":"1.048862847s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T17:05:37.211515Z","caller":"traceutil/trace.go:171","msg":"trace[1804067812] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"192.742278ms","start":"2026-02-25T17:05:37.018748Z","end":"2026-02-25T17:05:37.21149Z","steps":["trace[1804067812] 'process raft request' (duration: 192.518943ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:37.211556Z","caller":"traceutil/trace.go:171","msg":"trace[1982679048] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"101.573302ms","start":"2026-02-25T17:05:37.10996Z","end":"2026-02-25T17:05:37.211534Z","steps":["trace[1982679048] 'read index received' (duration: 101.228104ms)","trace[1982679048] 'applied index is now lower than readState.Index' (duration: 344.308µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:37.211662Z","caller":"traceutil/trace.go:171","msg":"trace[358583249] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"192.888252ms","start":"2026-02-25T17:05:37.018764Z","end":"2026-02-25T17:05:37.211652Z","steps":["trace[358583249] 'process raft request' (duration: 192.643406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:37.211669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.686684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:37.211701Z","caller":"traceutil/trace.go:171","msg":"trace[1099460283] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:781; }","duration":"101.784217ms","start":"2026-02-25T17:05:37.109906Z","end":"2026-02-25T17:05:37.21169Z","steps":["trace[1099460283] 'agreement among raft nodes before linearized reading' (duration: 101.707335ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:39.484047Z","caller":"traceutil/trace.go:171","msg":"trace[369996280] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"450.647313ms","start":"2026-02-25T17:05:39.033377Z","end":"2026-02-25T17:05:39.484025Z","steps":["trace[369996280] 'read index received' (duration: 366.925612ms)","trace[369996280] 'applied index is now lower than readState.Index' (duration: 83.720051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:39.484269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.882959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T17:05:39.484432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.161548ms","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-25T17:05:39.484459Z","caller":"traceutil/trace.go:171","msg":"trace[858625000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"269.23142ms","start":"2026-02-25T17:05:39.215218Z","end":"2026-02-25T17:05:39.484449Z","steps":["trace[858625000] 'agreement among raft nodes before linearized reading' (duration: 269.174458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:39.484725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.600095ms","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-25T17:05:39.48483Z","caller":"traceutil/trace.go:171","msg":"trace[1453298703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"268.757749ms","start":"2026-02-25T17:05:39.216049Z","end":"2026-02-25T17:05:39.484807Z","steps":["trace[1453298703] 'agreement among raft nodes before linearized reading' (duration: 268.466562ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:39.484938Z","caller":"traceutil/trace.go:171","msg":"trace[1628589419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"451.054343ms","start":"2026-02-25T17:05:39.03331Z","end":"2026-02-25T17:05:39.484364Z","steps":["trace[1628589419] 'agreement among raft nodes before linearized reading' (duration: 450.843238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:39.485063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:39.033281Z","time spent":"451.749839ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T17:05:39.768144Z","caller":"traceutil/trace.go:171","msg":"trace[1697752448] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"279.038951ms","start":"2026-02-25T17:05:39.489085Z","end":"2026-02-25T17:05:39.768124Z","steps":["trace[1697752448] 'process raft request' (duration: 278.848277ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:39.768354Z","caller":"traceutil/trace.go:171","msg":"trace[1265252277] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"279.244936ms","start":"2026-02-25T17:05:39.4891Z","end":"2026-02-25T17:05:39.768345Z","steps":["trace[1265252277] 'process raft request' (duration: 278.98465ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:43.960649Z","caller":"traceutil/trace.go:171","msg":"trace[1016602971] linearizableReadLoop","detail":"{readStateIndex:829; appliedIndex:828; }","duration":"108.541286ms","start":"2026-02-25T17:05:43.852083Z","end":"2026-02-25T17:05:43.960625Z","steps":["trace[1016602971] 'read index received' (duration: 108.319281ms)","trace[1016602971] 'applied index is now lower than readState.Index' (duration: 220.955µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:43.960773Z","caller":"traceutil/trace.go:171","msg":"trace[538968541] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"136.595397ms","start":"2026-02-25T17:05:43.824145Z","end":"2026-02-25T17:05:43.960741Z","steps":["trace[538968541] 'process raft request' (duration: 136.330891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:43.961389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.297524ms","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-25T17:05:43.961441Z","caller":"traceutil/trace.go:171","msg":"trace[184950979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"109.389796ms","start":"2026-02-25T17:05:43.852037Z","end":"2026-02-25T17:05:43.961427Z","steps":["trace[184950979] 'agreement among raft nodes before linearized reading' (duration: 108.743371ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:44.182781Z","caller":"traceutil/trace.go:171","msg":"trace[333873474] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"217.771778ms","start":"2026-02-25T17:05:43.964972Z","end":"2026-02-25T17:05:44.182743Z","steps":["trace[333873474] 'process raft request' (duration: 217.570633ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:44.183018Z","caller":"traceutil/trace.go:171","msg":"trace[1471320082] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"217.453451ms","start":"2026-02-25T17:05:43.96555Z","end":"2026-02-25T17:05:44.183004Z","steps":["trace[1471320082] 'process raft request' (duration: 217.149074ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:44.276029Z","caller":"traceutil/trace.go:171","msg":"trace[594854198] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"188.062039ms","start":"2026-02-25T17:05:44.087934Z","end":"2026-02-25T17:05:44.275996Z","steps":["trace[594854198] 'process raft request' (duration: 105.151317ms)","trace[594854198] 'compare' (duration: 82.707707ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:46.375187Z","caller":"traceutil/trace.go:171","msg":"trace[1685569118] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"182.857926ms","start":"2026-02-25T17:05:46.192292Z","end":"2026-02-25T17:05:46.37515Z","steps":["trace[1685569118] 'process raft request' (duration: 180.395518ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:54.107419Z","caller":"traceutil/trace.go:171","msg":"trace[733659055] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"243.865502ms","start":"2026-02-25T17:05:53.863538Z","end":"2026-02-25T17:05:54.107404Z","steps":["trace[733659055] 'read index received' (duration: 243.620877ms)","trace[733659055] 'applied index is now lower than readState.Index' (duration: 244.225µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:54.107577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.029466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T17:05:54.107548Z","caller":"traceutil/trace.go:171","msg":"trace[874783904] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"454.655267ms","start":"2026-02-25T17:05:53.652844Z","end":"2026-02-25T17:05:54.107499Z","steps":["trace[874783904] 'process raft request' (duration: 454.447342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:54.107713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:53.652827Z","time spent":"454.803521ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:54.107603Z","caller":"traceutil/trace.go:171","msg":"trace[1646912580] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:874; }","duration":"244.115348ms","start":"2026-02-25T17:05:53.863481Z","end":"2026-02-25T17:05:54.107596Z","steps":["trace[1646912580] 'agreement among raft nodes before linearized reading' (duration: 244.006945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:56.117845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958675054970248357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:56.618824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958675054970248357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:57.11949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958675054970248357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:57.619837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958675054970248357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:57.738801Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.709572227s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T17:05:57.739279Z","caller":"traceutil/trace.go:171","msg":"trace[103066561] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"2.710086439s","start":"2026-02-25T17:05:55.02913Z","end":"2026-02-25T17:05:57.739216Z","steps":["trace[103066561] 'process raft request' (duration: 2.709911565s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.739458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:55.029116Z","time spent":"2.710234402s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:57.769457Z","caller":"traceutil/trace.go:171","msg":"trace[811318930] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:915; }","duration":"2.152021777s","start":"2026-02-25T17:05:55.617414Z","end":"2026-02-25T17:05:57.769436Z","steps":["trace[811318930] 'read index received' (duration: 2.121606341s)","trace[811318930] 'applied index is now lower than readState.Index' (duration: 30.414456ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:57.769606Z","caller":"traceutil/trace.go:171","msg":"trace[1034643547] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"418.30317ms","start":"2026-02-25T17:05:57.351258Z","end":"2026-02-25T17:05:57.769562Z","steps":["trace[1034643547] 'process raft request' (duration: 418.042654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.769634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.152188531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:05:57.769698Z","caller":"traceutil/trace.go:171","msg":"trace[542952311] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:880; }","duration":"2.152307774s","start":"2026-02-25T17:05:55.617373Z","end":"2026-02-25T17:05:57.769681Z","steps":["trace[542952311] 'agreement among raft nodes before linearized reading' (duration: 2.152179451s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.769751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314139616s","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-25T17:05:57.769763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:55.617354Z","time spent":"2.152389006s","remote":"127.0.0.1:55118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:05:57.769784Z","caller":"traceutil/trace.go:171","msg":"trace[1582896240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"1.314204007s","start":"2026-02-25T17:05:56.455571Z","end":"2026-02-25T17:05:57.769775Z","steps":["trace[1582896240] 'agreement among raft nodes before linearized reading' (duration: 1.314101145s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.769807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.455553Z","time spent":"1.314249709s","remote":"127.0.0.1:55074","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-25T17:05:57.769863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.566884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T17:05:57.76988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41070835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T17:05:57.769908Z","caller":"traceutil/trace.go:171","msg":"trace[655407555] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:880; }","duration":"533.635886ms","start":"2026-02-25T17:05:57.236258Z","end":"2026-02-25T17:05:57.769894Z","steps":["trace[655407555] 'agreement among raft nodes before linearized reading' (duration: 533.557684ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:57.769928Z","caller":"traceutil/trace.go:171","msg":"trace[683009474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"1.410757471s","start":"2026-02-25T17:05:56.359156Z","end":"2026-02-25T17:05:57.769914Z","steps":["trace[683009474] 'agreement among raft nodes before linearized reading' (duration: 1.410593348s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.769999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.650491366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T17:05:57.770064Z","caller":"traceutil/trace.go:171","msg":"trace[941555777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"1.650576639s","start":"2026-02-25T17:05:56.11947Z","end":"2026-02-25T17:05:57.770047Z","steps":["trace[941555777] 'agreement among raft nodes before linearized reading' (duration: 1.650325813s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.770041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.359088Z","time spent":"1.410934946s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:05:57.770107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.119455Z","time spent":"1.6506393s","remote":"127.0.0.1:55074","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-25T17:05:57.769766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658039595s","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-25T17:05:57.770169Z","caller":"traceutil/trace.go:171","msg":"trace[525876125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"1.658475585s","start":"2026-02-25T17:05:56.111681Z","end":"2026-02-25T17:05:57.770157Z","steps":["trace[525876125] 'agreement among raft nodes before linearized reading' (duration: 1.657957933s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.76976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:57.351213Z","time spent":"418.465164ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:57.770201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.111666Z","time spent":"1.658524026s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T17:05:57.769941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:57.236243Z","time spent":"533.690627ms","remote":"127.0.0.1:55316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:05:57.76998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035594507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:57.770378Z","caller":"traceutil/trace.go:171","msg":"trace[1465074977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"1.036005537s","start":"2026-02-25T17:05:56.734345Z","end":"2026-02-25T17:05:57.770351Z","steps":["trace[1465074977] 'agreement among raft nodes before linearized reading' (duration: 1.035617748s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.770468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.734326Z","time spent":"1.036110519s","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:06:06.241954Z","caller":"traceutil/trace.go:171","msg":"trace[1555026606] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"420.597225ms","start":"2026-02-25T17:06:05.821332Z","end":"2026-02-25T17:06:06.241929Z","steps":["trace[1555026606] 'read index received' (duration: 420.361799ms)","trace[1555026606] 'applied index is now lower than readState.Index' (duration: 234.466µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:06.242172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.82095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T17:06:06.242193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.487486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T17:06:06.242221Z","caller":"traceutil/trace.go:171","msg":"trace[222589043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"413.531307ms","start":"2026-02-25T17:06:05.828682Z","end":"2026-02-25T17:06:06.242213Z","steps":["trace[222589043] 'agreement among raft nodes before linearized reading' (duration: 413.454725ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:06.24222Z","caller":"traceutil/trace.go:171","msg":"trace[566456453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:902; }","duration":"420.903612ms","start":"2026-02-25T17:06:05.821293Z","end":"2026-02-25T17:06:06.242196Z","steps":["trace[566456453] 'agreement among raft nodes before linearized reading' (duration: 420.765469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.242244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.828673Z","time spent":"413.565348ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:06:06.242252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.821275Z","time spent":"420.969063ms","remote":"127.0.0.1:55074","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-25T17:06:06.242294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.759557ms","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-25T17:06:06.242423Z","caller":"traceutil/trace.go:171","msg":"trace[401926617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:902; }","duration":"414.916161ms","start":"2026-02-25T17:06:05.827488Z","end":"2026-02-25T17:06:06.242404Z","steps":["trace[401926617] 'agreement among raft nodes before linearized reading' (duration: 414.623544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.242481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.827479Z","time spent":"414.981193ms","remote":"127.0.0.1:55074","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-25T17:06:06.242528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.284359ms","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-25T17:06:06.242562Z","caller":"traceutil/trace.go:171","msg":"trace[1045477454] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"412.335941ms","start":"2026-02-25T17:06:05.830218Z","end":"2026-02-25T17:06:06.242554Z","steps":["trace[1045477454] 'agreement among raft nodes before linearized reading' (duration: 411.90993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.242583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.830211Z","time spent":"412.366782ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T17:06:06.242081Z","caller":"traceutil/trace.go:171","msg":"trace[212091028] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"441.616529ms","start":"2026-02-25T17:06:05.800432Z","end":"2026-02-25T17:06:06.242048Z","steps":["trace[212091028] 'process raft request' (duration: 441.353983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.242698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.800402Z","time spent":"442.240404ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:06.554543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.59513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:06.554626Z","caller":"traceutil/trace.go:171","msg":"trace[502016772] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"307.749957ms","start":"2026-02-25T17:06:06.246863Z","end":"2026-02-25T17:06:06.554613Z","steps":["trace[502016772] 'process raft request' (duration: 156.031085ms)","trace[502016772] 'compare' (duration: 151.247961ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:06.554665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.246843Z","time spent":"307.805038ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:06.867267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.006937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:06.867455Z","caller":"traceutil/trace.go:171","msg":"trace[148705124] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:942; }","duration":"133.081694ms","start":"2026-02-25T17:06:06.734361Z","end":"2026-02-25T17:06:06.867443Z","steps":["trace[148705124] 'read index received' (duration: 32.391µs)","trace[148705124] 'applied index is now lower than readState.Index' (duration: 133.048643ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:06.867483Z","caller":"traceutil/trace.go:171","msg":"trace[987800074] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"620.364779ms","start":"2026-02-25T17:06:06.247101Z","end":"2026-02-25T17:06:06.867466Z","steps":["trace[987800074] 'process raft request' (duration: 620.305868ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:06.867483Z","caller":"traceutil/trace.go:171","msg":"trace[1162945473] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"620.363469ms","start":"2026-02-25T17:06:06.247102Z","end":"2026-02-25T17:06:06.867465Z","steps":["trace[1162945473] 'process raft request' (duration: 620.242526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.86752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.160846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:06.867539Z","caller":"traceutil/trace.go:171","msg":"trace[1588740652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"133.212867ms","start":"2026-02-25T17:06:06.734319Z","end":"2026-02-25T17:06:06.867532Z","steps":["trace[1588740652] 'agreement among raft nodes before linearized reading' (duration: 133.163986ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:06.867538Z","caller":"traceutil/trace.go:171","msg":"trace[1909749893] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"620.514193ms","start":"2026-02-25T17:06:06.247002Z","end":"2026-02-25T17:06:06.867516Z","steps":["trace[1909749893] 'process raft request' (duration: 466.163219ms)","trace[1909749893] 'compare' (duration: 153.903413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:06.867566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.247091Z","time spent":"620.42958ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:06.867567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.247049Z","time spent":"620.471251ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:06.867637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.246976Z","time spent":"620.606335ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:08.443697Z","caller":"traceutil/trace.go:171","msg":"trace[853039859] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"192.662997ms","start":"2026-02-25T17:06:08.251012Z","end":"2026-02-25T17:06:08.443675Z","steps":["trace[853039859] 'process raft request' (duration: 192.535954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:11.017394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.487191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T17:06:11.017469Z","caller":"traceutil/trace.go:171","msg":"trace[1382720905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"124.582063ms","start":"2026-02-25T17:06:10.89287Z","end":"2026-02-25T17:06:11.017452Z","steps":["trace[1382720905] 'range keys from in-memory index tree' (duration: 124.374768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:11.017656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.811389ms","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-25T17:06:11.017689Z","caller":"traceutil/trace.go:171","msg":"trace[408929506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:915; }","duration":"124.86656ms","start":"2026-02-25T17:06:10.892815Z","end":"2026-02-25T17:06:11.017682Z","steps":["trace[408929506] 'range keys from in-memory index tree' (duration: 124.650425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:11.017772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.221217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:11.01782Z","caller":"traceutil/trace.go:171","msg":"trace[2042883037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"285.305339ms","start":"2026-02-25T17:06:10.732498Z","end":"2026-02-25T17:06:11.017804Z","steps":["trace[2042883037] 'range keys from in-memory index tree' (duration: 285.142204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:11.017929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.07703ms","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-25T17:06:11.01797Z","caller":"traceutil/trace.go:171","msg":"trace[416810468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"135.155152ms","start":"2026-02-25T17:06:10.882805Z","end":"2026-02-25T17:06:11.01796Z","steps":["trace[416810468] 'range keys from in-memory index tree' (duration: 134.853455ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:12.684211Z","caller":"traceutil/trace.go:171","msg":"trace[972787215] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"123.908357ms","start":"2026-02-25T17:06:12.560282Z","end":"2026-02-25T17:06:12.68419Z","steps":["trace[972787215] 'read index received' (duration: 123.821165ms)","trace[972787215] 'applied index is now lower than readState.Index' (duration: 86.422µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:12.684483Z","caller":"traceutil/trace.go:171","msg":"trace[492604645] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"124.236055ms","start":"2026-02-25T17:06:12.560207Z","end":"2026-02-25T17:06:12.684443Z","steps":["trace[492604645] 'process raft request' (duration: 123.812125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:12.684517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.174574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:12.684593Z","caller":"traceutil/trace.go:171","msg":"trace[1441143091] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:919; }","duration":"124.332927ms","start":"2026-02-25T17:06:12.560248Z","end":"2026-02-25T17:06:12.684581Z","steps":["trace[1441143091] 'agreement among raft nodes before linearized reading' (duration: 124.138333ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:15.142364Z","caller":"traceutil/trace.go:171","msg":"trace[757114158] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"100.66428ms","start":"2026-02-25T17:06:15.041682Z","end":"2026-02-25T17:06:15.142346Z","steps":["trace[757114158] 'process raft request' (duration: 100.631629ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:15.142561Z","caller":"traceutil/trace.go:171","msg":"trace[1619054575] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"102.219128ms","start":"2026-02-25T17:06:15.040321Z","end":"2026-02-25T17:06:15.14254Z","steps":["trace[1619054575] 'process raft request' (duration: 100.312362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:15.538452Z","caller":"traceutil/trace.go:171","msg":"trace[1395856490] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"111.752672ms","start":"2026-02-25T17:06:15.426677Z","end":"2026-02-25T17:06:15.53843Z","steps":["trace[1395856490] 'process raft request' (duration: 63.22458ms)","trace[1395856490] 'compare' (duration: 48.412169ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:18.79646Z","caller":"traceutil/trace.go:171","msg":"trace[146404894] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"799.491578ms","start":"2026-02-25T17:06:17.996943Z","end":"2026-02-25T17:06:18.796435Z","steps":["trace[146404894] 'process raft request' (duration: 799.197961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:18.796654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:17.99692Z","time spent":"799.650651ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:18.986986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.532632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:18.987079Z","caller":"traceutil/trace.go:171","msg":"trace[998710959] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"160.399987ms","start":"2026-02-25T17:06:18.826665Z","end":"2026-02-25T17:06:18.987065Z","steps":["trace[998710959] 'read index received' (duration: 38.725381ms)","trace[998710959] 'applied index is now lower than readState.Index' (duration: 121.673436ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:18.98713Z","caller":"traceutil/trace.go:171","msg":"trace[1131502122] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"185.940898ms","start":"2026-02-25T17:06:18.80115Z","end":"2026-02-25T17:06:18.987091Z","steps":["trace[1131502122] 'process raft request' (duration: 64.235092ms)","trace[1131502122] 'compare' (duration: 121.42602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:18.987265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.598471ms","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-25T17:06:18.987295Z","caller":"traceutil/trace.go:171","msg":"trace[987303957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"160.663463ms","start":"2026-02-25T17:06:18.826623Z","end":"2026-02-25T17:06:18.987286Z","steps":["trace[987303957] 'agreement among raft nodes before linearized reading' (duration: 160.476568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:19.393827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.111446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:19.393986Z","caller":"traceutil/trace.go:171","msg":"trace[1034615728] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"365.673511ms","start":"2026-02-25T17:06:19.028289Z","end":"2026-02-25T17:06:19.393962Z","steps":["trace[1034615728] 'process raft request' (duration: 99.332399ms)","trace[1034615728] 'compare' (duration: 265.930972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:19.394067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:19.028264Z","time spent":"365.760493ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:19.471803Z","caller":"traceutil/trace.go:171","msg":"trace[810736170] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:994; }","duration":"319.514504ms","start":"2026-02-25T17:06:19.152262Z","end":"2026-02-25T17:06:19.471777Z","steps":["trace[810736170] 'read index received' (duration: 42.671385ms)","trace[810736170] 'applied index is now lower than readState.Index' (duration: 276.841559ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:19.472055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.911532ms","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-25T17:06:19.472119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.529752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T17:06:19.472152Z","caller":"traceutil/trace.go:171","msg":"trace[1566223308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"300.008295ms","start":"2026-02-25T17:06:19.172093Z","end":"2026-02-25T17:06:19.472101Z","steps":["trace[1566223308] 'agreement among raft nodes before linearized reading' (duration: 299.897302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:19.472183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:19.17208Z","time spent":"300.097027ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T17:06:19.472179Z","caller":"traceutil/trace.go:171","msg":"trace[858498381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:953; }","duration":"304.640284ms","start":"2026-02-25T17:06:19.167524Z","end":"2026-02-25T17:06:19.472164Z","steps":["trace[858498381] 'agreement among raft nodes before linearized reading' (duration: 304.428589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:19.47222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:19.167505Z","time spent":"304.706705ms","remote":"127.0.0.1:55074","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-25T17:06:19.4724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.147608ms","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-25T17:06:19.472424Z","caller":"traceutil/trace.go:171","msg":"trace[1156935420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"320.2134ms","start":"2026-02-25T17:06:19.152203Z","end":"2026-02-25T17:06:19.472416Z","steps":["trace[1156935420] 'agreement among raft nodes before linearized reading' (duration: 319.678178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:19.472451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:19.152182Z","time spent":"320.264762ms","remote":"127.0.0.1:55074","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-25T17:06:19.632223Z","caller":"traceutil/trace.go:171","msg":"trace[1061976082] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"153.238168ms","start":"2026-02-25T17:06:19.478957Z","end":"2026-02-25T17:06:19.632195Z","steps":["trace[1061976082] 'process raft request' (duration: 153.172336ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:19.632263Z","caller":"traceutil/trace.go:171","msg":"trace[1525530187] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"154.15429ms","start":"2026-02-25T17:06:19.478092Z","end":"2026-02-25T17:06:19.632247Z","steps":["trace[1525530187] 'process raft request' (duration: 153.872813ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:21.18025Z","caller":"traceutil/trace.go:171","msg":"trace[943090496] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"446.69982ms","start":"2026-02-25T17:06:20.733532Z","end":"2026-02-25T17:06:21.180232Z","steps":["trace[943090496] 'read index received' (duration: 446.560927ms)","trace[943090496] 'applied index is now lower than readState.Index' (duration: 138.093µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:21.181051Z","caller":"traceutil/trace.go:171","msg":"trace[1060192595] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"604.882084ms","start":"2026-02-25T17:06:20.575465Z","end":"2026-02-25T17:06:21.180348Z","steps":["trace[1060192595] 'process raft request' (duration: 604.617838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:21.181161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.617841ms","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-25T17:06:21.181209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.575427Z","time spent":"605.695993ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:21.181253Z","caller":"traceutil/trace.go:171","msg":"trace[661459541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"188.769524ms","start":"2026-02-25T17:06:20.992464Z","end":"2026-02-25T17:06:21.181233Z","steps":["trace[661459541] 'agreement among raft nodes before linearized reading' (duration: 188.478388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:21.181249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.379988ms","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-25T17:06:21.181068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.533589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:21.181332Z","caller":"traceutil/trace.go:171","msg":"trace[1382497088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"138.515911ms","start":"2026-02-25T17:06:21.042798Z","end":"2026-02-25T17:06:21.181314Z","steps":["trace[1382497088] 'agreement among raft nodes before linearized reading' (duration: 138.110872ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:21.181329Z","caller":"traceutil/trace.go:171","msg":"trace[466816060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"447.845916ms","start":"2026-02-25T17:06:20.733473Z","end":"2026-02-25T17:06:21.181319Z","steps":["trace[466816060] 'agreement among raft nodes before linearized reading' (duration: 446.858634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:21.181413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.733451Z","time spent":"447.955018ms","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:21.181446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.076635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:21.181529Z","caller":"traceutil/trace.go:171","msg":"trace[1240536930] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:957; }","duration":"342.213899ms","start":"2026-02-25T17:06:20.839293Z","end":"2026-02-25T17:06:21.181507Z","steps":["trace[1240536930] 'agreement among raft nodes before linearized reading' (duration: 341.115943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:21.181578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:20.839276Z","time spent":"342.29073ms","remote":"127.0.0.1:34124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:22.089444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.519338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:22.089592Z","caller":"traceutil/trace.go:171","msg":"trace[1402659230] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"547.573904ms","start":"2026-02-25T17:06:21.541995Z","end":"2026-02-25T17:06:22.089569Z","steps":["trace[1402659230] 'read index received' (duration: 38.851µs)","trace[1402659230] 'applied index is now lower than readState.Index' (duration: 547.533153ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:22.089681Z","caller":"traceutil/trace.go:171","msg":"trace[1882394525] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"904.268343ms","start":"2026-02-25T17:06:21.185392Z","end":"2026-02-25T17:06:22.089661Z","steps":["trace[1882394525] 'process raft request' (duration: 242.435108ms)","trace[1882394525] 'compare' (duration: 661.365054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:22.089773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:21.185365Z","time spent":"904.361046ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:22.089804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.807429ms","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-25T17:06:22.089844Z","caller":"traceutil/trace.go:171","msg":"trace[759109870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"547.885772ms","start":"2026-02-25T17:06:21.541946Z","end":"2026-02-25T17:06:22.089832Z","steps":["trace[759109870] 'agreement among raft nodes before linearized reading' (duration: 547.736868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:22.089896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:21.541927Z","time spent":"547.957374ms","remote":"127.0.0.1:55074","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-25T17:06:22.089926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.863508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-25T17:06:22.089955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.178162ms","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-25T17:06:22.089991Z","caller":"traceutil/trace.go:171","msg":"trace[1630751992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:959; }","duration":"453.96146ms","start":"2026-02-25T17:06:21.636012Z","end":"2026-02-25T17:06:22.089974Z","steps":["trace[1630751992] 'agreement among raft nodes before linearized reading' (duration: 453.783105ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:22.090003Z","caller":"traceutil/trace.go:171","msg":"trace[37763413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"453.259454ms","start":"2026-02-25T17:06:21.63673Z","end":"2026-02-25T17:06:22.08999Z","steps":["trace[37763413] 'agreement among raft nodes before linearized reading' (duration: 453.11576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:22.090032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:21.635989Z","time spent":"454.034322ms","remote":"127.0.0.1:55074","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-25T17:06:22.090047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:21.636711Z","time spent":"453.325196ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:06:22.089926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.627078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:22.090193Z","caller":"traceutil/trace.go:171","msg":"trace[828299037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"356.951316ms","start":"2026-02-25T17:06:21.733225Z","end":"2026-02-25T17:06:22.090176Z","steps":["trace[828299037] 'agreement among raft nodes before linearized reading' (duration: 356.606418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:22.090252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:21.733203Z","time spent":"357.035648ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:22.431691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.240222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:22.431825Z","caller":"traceutil/trace.go:171","msg":"trace[531403731] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"337.884777ms","start":"2026-02-25T17:06:22.093914Z","end":"2026-02-25T17:06:22.431798Z","steps":["trace[531403731] 'process raft request' (duration: 228.43444ms)","trace[531403731] 'compare' (duration: 109.107858ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:22.43192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:22.093902Z","time spent":"337.97438ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:22.627884Z","caller":"traceutil/trace.go:171","msg":"trace[1475218217] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"533.630607ms","start":"2026-02-25T17:06:22.094232Z","end":"2026-02-25T17:06:22.627862Z","steps":["trace[1475218217] 'process raft request' (duration: 533.579606ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:22.627944Z","caller":"traceutil/trace.go:171","msg":"trace[1581181235] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"533.827451ms","start":"2026-02-25T17:06:22.094082Z","end":"2026-02-25T17:06:22.62791Z","steps":["trace[1581181235] 'process raft request' (duration: 449.508395ms)","trace[1581181235] 'compare' (duration: 84.019768ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:22.62802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:22.094223Z","time spent":"533.728939ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:22.628155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:22.094069Z","time spent":"533.984796ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:22.974582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.472833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:22.974658Z","caller":"traceutil/trace.go:171","msg":"trace[295922608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"240.562445ms","start":"2026-02-25T17:06:22.734079Z","end":"2026-02-25T17:06:22.974641Z","steps":["trace[295922608] 'range keys from in-memory index tree' (duration: 240.405642ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:23.789638Z","caller":"traceutil/trace.go:171","msg":"trace[1903717187] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"349.51268ms","start":"2026-02-25T17:06:23.440097Z","end":"2026-02-25T17:06:23.78961Z","steps":["trace[1903717187] 'process raft request' (duration: 296.432399ms)","trace[1903717187] 'compare' (duration: 52.886096ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:23.789762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:23.440074Z","time spent":"349.632494ms","remote":"127.0.0.1:54890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":848,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:06:24.550554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958675054970248569,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:25.009932Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.144327158s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T17:06:25.804393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.311624ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-25T17:06:25.80448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:23.865415Z","time spent":"1.939059124s","remote":"127.0.0.1:54852","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T17:06:25.804479Z","caller":"traceutil/trace.go:171","msg":"trace[82620864] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"1.754391695s","start":"2026-02-25T17:06:24.050071Z","end":"2026-02-25T17:06:25.804463Z","steps":["trace[82620864] 'read index received' (duration: 959.943626ms)","trace[82620864] 'applied index is now lower than readState.Index' (duration: 794.446638ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:25.804649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754548408s","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-25T17:06:25.80477Z","caller":"traceutil/trace.go:171","msg":"trace[196479795] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:964; }","duration":"1.754711942s","start":"2026-02-25T17:06:24.050033Z","end":"2026-02-25T17:06:25.804745Z","steps":["trace[196479795] 'agreement among raft nodes before linearized reading' (duration: 1.754528038s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.804899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:24.050015Z","time spent":"1.754866625s","remote":"127.0.0.1:43490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:25.804948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172606084s","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-25T17:06:25.80501Z","caller":"traceutil/trace.go:171","msg":"trace[61723618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"1.172731347s","start":"2026-02-25T17:06:24.632264Z","end":"2026-02-25T17:06:25.804995Z","steps":["trace[61723618] 'agreement among raft nodes before linearized reading' (duration: 1.172527382s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.805044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:24.63224Z","time spent":"1.172798089s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T17:06:25.804825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.439792ms","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-25T17:06:25.804918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369509971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T17:06:25.805132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1724158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T17:06:25.805175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.543291533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:25.805182Z","caller":"traceutil/trace.go:171","msg":"trace[793682771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:964; }","duration":"1.172473031s","start":"2026-02-25T17:06:24.632693Z","end":"2026-02-25T17:06:25.805166Z","steps":["trace[793682771] 'agreement among raft nodes before linearized reading' (duration: 1.172380449s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:25.805201Z","caller":"traceutil/trace.go:171","msg":"trace[1410113244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"1.543343784s","start":"2026-02-25T17:06:24.26185Z","end":"2026-02-25T17:06:25.805194Z","steps":["trace[1410113244] 'agreement among raft nodes before linearized reading' (duration: 1.543298323s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.805217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:24.632686Z","time spent":"1.172524243s","remote":"127.0.0.1:55074","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-25T17:06:25.805227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:24.261834Z","time spent":"1.543389595s","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:25.805112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.918139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:25.805264Z","caller":"traceutil/trace.go:171","msg":"trace[1905398174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"1.369835588s","start":"2026-02-25T17:06:24.435351Z","end":"2026-02-25T17:06:25.805187Z","steps":["trace[1905398174] 'agreement among raft nodes before linearized reading' (duration: 1.369436389s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.805357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:24.435331Z","time spent":"1.370012242s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T17:06:25.805295Z","caller":"traceutil/trace.go:171","msg":"trace[268426728] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:964; }","duration":"406.141204ms","start":"2026-02-25T17:06:25.39914Z","end":"2026-02-25T17:06:25.805281Z","steps":["trace[268426728] 'agreement among raft nodes before linearized reading' (duration: 405.927099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.805436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.399109Z","time spent":"406.316598ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:06:25.805121Z","caller":"traceutil/trace.go:171","msg":"trace[912288798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:964; }","duration":"454.77794ms","start":"2026-02-25T17:06:25.350314Z","end":"2026-02-25T17:06:25.805092Z","steps":["trace[912288798] 'agreement among raft nodes before linearized reading' (duration: 454.36478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.805514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.350288Z","time spent":"455.183799ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T17:06:25.805393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.71090483s","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-25T17:06:25.806003Z","caller":"traceutil/trace.go:171","msg":"trace[928120242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"1.711545355s","start":"2026-02-25T17:06:24.09444Z","end":"2026-02-25T17:06:25.805986Z","steps":["trace[928120242] 'agreement among raft nodes before linearized reading' (duration: 1.710194273s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.807029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:24.094422Z","time spent":"1.711605637s","remote":"127.0.0.1:55074","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-25T17:06:26.087044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.033199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:26.087178Z","caller":"traceutil/trace.go:171","msg":"trace[1862763724] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"281.404327ms","start":"2026-02-25T17:06:25.805753Z","end":"2026-02-25T17:06:26.087158Z","steps":["trace[1862763724] 'read index received' (duration: 115.07091ms)","trace[1862763724] 'applied index is now lower than readState.Index' (duration: 166.331617ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:26.087314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.55378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:26.087354Z","caller":"traceutil/trace.go:171","msg":"trace[996309374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"281.608801ms","start":"2026-02-25T17:06:25.805731Z","end":"2026-02-25T17:06:26.087339Z","steps":["trace[996309374] 'agreement among raft nodes before linearized reading' (duration: 281.482698ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:26.087459Z","caller":"traceutil/trace.go:171","msg":"trace[862213046] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"282.140064ms","start":"2026-02-25T17:06:25.805259Z","end":"2026-02-25T17:06:26.087399Z","steps":["trace[862213046] 'process raft request' (duration: 115.591062ms)","trace[862213046] 'compare' (duration: 165.886436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:26.591334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958675054970248580,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:27.481819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.349529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:27.482119Z","caller":"traceutil/trace.go:171","msg":"trace[1414715021] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1009; }","duration":"1.391746153s","start":"2026-02-25T17:06:26.090353Z","end":"2026-02-25T17:06:27.482099Z","steps":["trace[1414715021] 'read index received' (duration: 689.028995ms)","trace[1414715021] 'applied index is now lower than readState.Index' (duration: 702.716058ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:27.482218Z","caller":"traceutil/trace.go:171","msg":"trace[152822555] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.672000692s","start":"2026-02-25T17:06:25.810209Z","end":"2026-02-25T17:06:27.482209Z","steps":["trace[152822555] 'process raft request' (duration: 1.671806088s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:27.482244Z","caller":"traceutil/trace.go:171","msg":"trace[502739995] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"1.672360681s","start":"2026-02-25T17:06:25.809858Z","end":"2026-02-25T17:06:27.482219Z","steps":["trace[502739995] 'process raft request' (duration: 1.672115976s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:27.482307Z","caller":"traceutil/trace.go:171","msg":"trace[939743966] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.673693213s","start":"2026-02-25T17:06:25.808588Z","end":"2026-02-25T17:06:27.482282Z","steps":["trace[939743966] 'process raft request' (duration: 970.768381ms)","trace[939743966] 'compare' (duration: 701.779475ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:27.48237Z","caller":"traceutil/trace.go:171","msg":"trace[1030517463] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.671244805s","start":"2026-02-25T17:06:25.811109Z","end":"2026-02-25T17:06:27.482354Z","steps":["trace[1030517463] 'process raft request' (duration: 1.670940758s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:27.482414Z","caller":"traceutil/trace.go:171","msg":"trace[1927141203] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"1.672758101s","start":"2026-02-25T17:06:25.809648Z","end":"2026-02-25T17:06:27.482406Z","steps":["trace[1927141203] 'process raft request' (duration: 1.672260459s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:27.482414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.808575Z","time spent":"1.673782515s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:27.482454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.811096Z","time spent":"1.671310477s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:27.482455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296833888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-02-25T17:06:27.482466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.809627Z","time spent":"1.672816633s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:27.482492Z","caller":"traceutil/trace.go:171","msg":"trace[1097138111] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:970; }","duration":"1.296907951s","start":"2026-02-25T17:06:26.185574Z","end":"2026-02-25T17:06:27.482482Z","steps":["trace[1097138111] 'agreement among raft nodes before linearized reading' (duration: 1.296778127s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:27.482377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.809831Z","time spent":"1.672472894s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:27.482531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:26.185552Z","time spent":"1.296972142s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T17:06:27.482314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.81019Z","time spent":"1.672078415s","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:27.482309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391937037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-02-25T17:06:27.4826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.597699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:27.482613Z","caller":"traceutil/trace.go:171","msg":"trace[136579693] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:970; }","duration":"1.392291526s","start":"2026-02-25T17:06:26.090313Z","end":"2026-02-25T17:06:27.482604Z","steps":["trace[136579693] 'agreement among raft nodes before linearized reading' (duration: 1.391896586s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:27.482598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.347572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:27.482679Z","caller":"traceutil/trace.go:171","msg":"trace[157926615] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:970; }","duration":"373.487206ms","start":"2026-02-25T17:06:27.109174Z","end":"2026-02-25T17:06:27.482662Z","steps":["trace[157926615] 'agreement among raft nodes before linearized reading' (duration: 373.317762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:27.482716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223519782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T17:06:27.482766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:27.109141Z","time spent":"373.608259ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:06:27.482778Z","caller":"traceutil/trace.go:171","msg":"trace[301422475] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:970; }","duration":"1.223623065s","start":"2026-02-25T17:06:26.259139Z","end":"2026-02-25T17:06:27.482762Z","steps":["trace[301422475] 'agreement among raft nodes before linearized reading' (duration: 1.223517683s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:27.482828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:26.259123Z","time spent":"1.223692596s","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:06:27.482633Z","caller":"traceutil/trace.go:171","msg":"trace[1815336531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"748.660271ms","start":"2026-02-25T17:06:26.733964Z","end":"2026-02-25T17:06:27.482624Z","steps":["trace[1815336531] 'agreement among raft nodes before linearized reading' (duration: 748.598529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:27.482932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:26.733946Z","time spent":"748.941987ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:27.482642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:26.090292Z","time spent":"1.392342957s","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T17:06:28.164898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.424977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:28.165011Z","caller":"traceutil/trace.go:171","msg":"trace[187986562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"430.568939ms","start":"2026-02-25T17:06:27.734416Z","end":"2026-02-25T17:06:28.164985Z","steps":["trace[187986562] 'range keys from in-memory index tree' (duration: 430.342914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:28.165066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:27.734396Z","time spent":"430.656762ms","remote":"127.0.0.1:54810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:06:29.716319Z","caller":"traceutil/trace.go:171","msg":"trace[1052966200] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"224.773033ms","start":"2026-02-25T17:06:29.491526Z","end":"2026-02-25T17:06:29.716299Z","steps":["trace[1052966200] 'process raft request' (duration: 224.601869ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:29.716627Z","caller":"traceutil/trace.go:171","msg":"trace[1529260268] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"224.905726ms","start":"2026-02-25T17:06:29.491697Z","end":"2026-02-25T17:06:29.716603Z","steps":["trace[1529260268] 'process raft request' (duration: 224.563158ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:29.716728Z","caller":"traceutil/trace.go:171","msg":"trace[609452748] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"224.445905ms","start":"2026-02-25T17:06:29.492266Z","end":"2026-02-25T17:06:29.716712Z","steps":["trace[609452748] 'process raft request' (duration: 224.235951ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:29.716724Z","caller":"traceutil/trace.go:171","msg":"trace[423887415] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"224.370483ms","start":"2026-02-25T17:06:29.492329Z","end":"2026-02-25T17:06:29.7167Z","steps":["trace[423887415] 'process raft request' (duration: 224.322172ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:29.717608Z","caller":"traceutil/trace.go:171","msg":"trace[228056375] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"206.060582ms","start":"2026-02-25T17:06:29.511531Z","end":"2026-02-25T17:06:29.717592Z","steps":["trace[228056375] 'process raft request' (duration: 205.97063ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:33.895165Z","caller":"traceutil/trace.go:171","msg":"trace[770204496] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"110.637576ms","start":"2026-02-25T17:06:33.784497Z","end":"2026-02-25T17:06:33.895134Z","steps":["trace[770204496] 'process raft request' (duration: 59.325137ms)","trace[770204496] 'compare' (duration: 51.015862ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:33.895358Z","caller":"traceutil/trace.go:171","msg":"trace[309665764] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"110.664427ms","start":"2026-02-25T17:06:33.784663Z","end":"2026-02-25T17:06:33.895327Z","steps":["trace[309665764] 'process raft request' (duration: 110.337239ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:36.054998Z","caller":"traceutil/trace.go:171","msg":"trace[1196617164] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"150.72671ms","start":"2026-02-25T17:06:35.904249Z","end":"2026-02-25T17:06:36.054976Z","steps":["trace[1196617164] 'process raft request' (duration: 150.596947ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:36.065283Z","caller":"traceutil/trace.go:171","msg":"trace[2010344962] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"160.598922ms","start":"2026-02-25T17:06:35.904667Z","end":"2026-02-25T17:06:36.065266Z","steps":["trace[2010344962] 'process raft request' (duration: 160.506529ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:38.960851Z","caller":"traceutil/trace.go:171","msg":"trace[1193709516] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"161.531857ms","start":"2026-02-25T17:06:38.799298Z","end":"2026-02-25T17:06:38.96083Z","steps":["trace[1193709516] 'process raft request' (duration: 161.411864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:40.192267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.34291ms","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-25T17:06:40.192344Z","caller":"traceutil/trace.go:171","msg":"trace[1394145277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1034; }","duration":"125.458583ms","start":"2026-02-25T17:06:40.066859Z","end":"2026-02-25T17:06:40.192318Z","steps":["trace[1394145277] 'range keys from in-memory index tree' (duration: 125.194227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:40.192267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.938907ms","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-25T17:06:40.192444Z","caller":"traceutil/trace.go:171","msg":"trace[850919416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"101.157373ms","start":"2026-02-25T17:06:40.091269Z","end":"2026-02-25T17:06:40.192426Z","steps":["trace[850919416] 'range keys from in-memory index tree' (duration: 100.791704ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:40.330085Z","caller":"traceutil/trace.go:171","msg":"trace[1660255891] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"132.325507ms","start":"2026-02-25T17:06:40.197742Z","end":"2026-02-25T17:06:40.330067Z","steps":["trace[1660255891] 'process raft request' (duration: 123.371187ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:40.330467Z","caller":"traceutil/trace.go:171","msg":"trace[1780291149] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"126.002476ms","start":"2026-02-25T17:06:40.20444Z","end":"2026-02-25T17:06:40.330443Z","steps":["trace[1780291149] 'process raft request' (duration: 125.592897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:40.945335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.841822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:40.945419Z","caller":"traceutil/trace.go:171","msg":"trace[885011857] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1045; }","duration":"130.964995ms","start":"2026-02-25T17:06:40.814436Z","end":"2026-02-25T17:06:40.945401Z","steps":["trace[885011857] 'range keys from in-memory index tree' (duration: 130.768241ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:41.109771Z","caller":"traceutil/trace.go:171","msg":"trace[1535540468] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"163.88122ms","start":"2026-02-25T17:06:40.945864Z","end":"2026-02-25T17:06:41.109745Z","steps":["trace[1535540468] 'process raft request' (duration: 163.719086ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:41.137002Z","caller":"traceutil/trace.go:171","msg":"trace[1200131102] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"188.432796ms","start":"2026-02-25T17:06:40.948545Z","end":"2026-02-25T17:06:41.136978Z","steps":["trace[1200131102] 'process raft request' (duration: 188.314264ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:41.266763Z","caller":"traceutil/trace.go:171","msg":"trace[496665018] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"152.887674ms","start":"2026-02-25T17:06:41.113824Z","end":"2026-02-25T17:06:41.266712Z","steps":["trace[496665018] 'process raft request' (duration: 62.907431ms)","trace[496665018] 'compare' (duration: 89.262418ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:41.266869Z","caller":"traceutil/trace.go:171","msg":"trace[371710373] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1094; }","duration":"125.822272ms","start":"2026-02-25T17:06:41.140998Z","end":"2026-02-25T17:06:41.266821Z","steps":["trace[371710373] 'read index received' (duration: 35.751327ms)","trace[371710373] 'applied index is now lower than readState.Index' (duration: 90.065635ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:41.267649Z","caller":"traceutil/trace.go:171","msg":"trace[482571975] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"125.238648ms","start":"2026-02-25T17:06:41.142392Z","end":"2026-02-25T17:06:41.26763Z","steps":["trace[482571975] 'process raft request' (duration: 125.094864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:41.268522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.432375ms","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:533"} {"level":"info","ts":"2026-02-25T17:06:41.268916Z","caller":"traceutil/trace.go:171","msg":"trace[1793218613] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1048; }","duration":"127.601252ms","start":"2026-02-25T17:06:41.140961Z","end":"2026-02-25T17:06:41.268562Z","steps":["trace[1793218613] 'agreement among raft nodes before linearized reading' (duration: 126.154579ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:41.830934Z","caller":"traceutil/trace.go:171","msg":"trace[160180794] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"103.822641ms","start":"2026-02-25T17:06:41.727081Z","end":"2026-02-25T17:06:41.830903Z","steps":["trace[160180794] 'process raft request' (duration: 102.83855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:42.105553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.457994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-zkckq\" ","response":"range_response_count:1 size:12337"} {"level":"info","ts":"2026-02-25T17:06:42.105651Z","caller":"traceutil/trace.go:171","msg":"trace[629637853] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-zkckq; range_end:; response_count:1; response_revision:1054; }","duration":"143.586647ms","start":"2026-02-25T17:06:41.962038Z","end":"2026-02-25T17:06:42.105625Z","steps":["trace[629637853] 'range keys from in-memory index tree' (duration: 143.27172ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:42.231673Z","caller":"traceutil/trace.go:171","msg":"trace[368156401] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"115.046252ms","start":"2026-02-25T17:06:42.116596Z","end":"2026-02-25T17:06:42.231642Z","steps":["trace[368156401] 'process raft request' (duration: 114.868478ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:09:36.28799Z","caller":"traceutil/trace.go:171","msg":"trace[1662795415] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1876; }","duration":"163.933308ms","start":"2026-02-25T17:09:36.124033Z","end":"2026-02-25T17:09:36.287966Z","steps":["trace[1662795415] 'read index received' (duration: 163.890048ms)","trace[1662795415] 'applied index is now lower than readState.Index' (duration: 42.15µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:09:36.288179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.128493ms","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":"info","ts":"2026-02-25T17:09:36.28821Z","caller":"traceutil/trace.go:171","msg":"trace[1930320504] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1794; }","duration":"164.205404ms","start":"2026-02-25T17:09:36.123996Z","end":"2026-02-25T17:09:36.288201Z","steps":["trace[1930320504] 'agreement among raft nodes before linearized reading' (duration: 164.078351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:09:36.288122Z","caller":"traceutil/trace.go:171","msg":"trace[856158012] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"194.236626ms","start":"2026-02-25T17:09:36.093848Z","end":"2026-02-25T17:09:36.288085Z","steps":["trace[856158012] 'process raft request' (duration: 193.988481ms)"],"step_count":1}