{"level":"warn","ts":"2026-02-25T21:49:21.837463Z","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-25T21:49:21.837588Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-25T21:49:21.837662Z","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-25T21:49:21.837679Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-02-25T21:49:21.837699Z","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-25T21:49:21.839539Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-02-25T21:49:21.839644Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-25T21:49:21.847641Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.714332ms"} {"level":"info","ts":"2026-02-25T21:49:21.859678Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-02-25T21:49:21.859781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T21:49:21.859808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-02-25T21:49:21.85982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T21:49:21.859836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-02-25T21:49:21.859875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-02-25T21:49:21.86553Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T21:49:21.86806Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T21:49:21.871361Z","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-25T21:49:21.87407Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T21:49:21.874236Z","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-25T21:49:21.87434Z","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-25T21:49:21.87449Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T21:49:21.87435Z","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-25T21:49:21.878057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-02-25T21:49:21.878287Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-02-25T21:49:21.879126Z","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-25T21:49:21.879374Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T21:49:21.879416Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T21:49:21.879515Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-02-25T21:49:21.879533Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-02-25T21:49:22.160696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T21:49:22.16095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T21:49:22.161136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-02-25T21:49:22.161276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-02-25T21:49:22.16145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-02-25T21:49:22.161626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-02-25T21:49:22.161765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-02-25T21:49:22.162912Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T21:49:22.162931Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T21:49:22.163289Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T21:49:22.163405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T21:49:22.164243Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T21:49:22.164434Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T21:49:22.164488Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T21:49:22.165123Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T21:49:22.165171Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T21:49:22.165705Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T21:49:22.168123Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"warn","ts":"2026-02-25T21:49:45.411415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.054323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T21:49:45.411523Z","caller":"traceutil/trace.go:171","msg":"trace[101910959] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:462; }","duration":"157.206946ms","start":"2026-02-25T21:49:45.254297Z","end":"2026-02-25T21:49:45.411504Z","steps":["trace[101910959] 'range keys from in-memory index tree' (duration: 156.92898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:49.808843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.160828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T21:49:49.808939Z","caller":"traceutil/trace.go:171","msg":"trace[195013856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:507; }","duration":"116.30064ms","start":"2026-02-25T21:49:49.692617Z","end":"2026-02-25T21:49:49.808917Z","steps":["trace[195013856] 'range keys from in-memory index tree' (duration: 116.058895ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:49:50.96459Z","caller":"traceutil/trace.go:171","msg":"trace[707320000] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"203.193587ms","start":"2026-02-25T21:49:50.76137Z","end":"2026-02-25T21:49:50.964563Z","steps":["trace[707320000] 'read index received' (duration: 198.848758ms)","trace[707320000] 'applied index is now lower than readState.Index' (duration: 4.343739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:49:50.964707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.321681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:49:50.96477Z","caller":"traceutil/trace.go:171","msg":"trace[1860557866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"203.459884ms","start":"2026-02-25T21:49:50.761294Z","end":"2026-02-25T21:49:50.964754Z","steps":["trace[1860557866] 'agreement among raft nodes before linearized reading' (duration: 203.351862ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:49:53.440418Z","caller":"traceutil/trace.go:171","msg":"trace[1541401525] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"459.46629ms","start":"2026-02-25T21:49:52.980931Z","end":"2026-02-25T21:49:53.440398Z","steps":["trace[1541401525] 'read index received' (duration: 459.328546ms)","trace[1541401525] 'applied index is now lower than readState.Index' (duration: 136.693µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:49:53.440523Z","caller":"traceutil/trace.go:171","msg":"trace[830513697] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"463.981312ms","start":"2026-02-25T21:49:52.976493Z","end":"2026-02-25T21:49:53.440475Z","steps":["trace[830513697] 'process raft request' (duration: 463.774488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:53.440613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.658634ms","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-25T21:49:53.440709Z","caller":"traceutil/trace.go:171","msg":"trace[1188665766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"459.788977ms","start":"2026-02-25T21:49:52.980898Z","end":"2026-02-25T21:49:53.440687Z","steps":["trace[1188665766] 'agreement among raft nodes before linearized reading' (duration: 459.577142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:53.440765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:49:52.980887Z","time spent":"459.869199ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T21:49:53.441273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:49:52.976452Z","time spent":"464.196937ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:49:57.659524Z","caller":"traceutil/trace.go:171","msg":"trace[1947128151] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"206.680096ms","start":"2026-02-25T21:49:57.452814Z","end":"2026-02-25T21:49:57.659494Z","steps":["trace[1947128151] 'read index received' (duration: 206.659506ms)","trace[1947128151] 'applied index is now lower than readState.Index' (duration: 19.76µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:49:57.659556Z","caller":"traceutil/trace.go:171","msg":"trace[1701553521] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"414.247536ms","start":"2026-02-25T21:49:57.24527Z","end":"2026-02-25T21:49:57.659518Z","steps":["trace[1701553521] 'process raft request' (duration: 413.9949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:57.659742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:49:57.245252Z","time spent":"414.407099ms","remote":"127.0.0.1:35506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:49:57.659824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.948623ms","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-25T21:49:57.659991Z","caller":"traceutil/trace.go:171","msg":"trace[728361689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"207.179549ms","start":"2026-02-25T21:49:57.452759Z","end":"2026-02-25T21:49:57.659939Z","steps":["trace[728361689] 'agreement among raft nodes before linearized reading' (duration: 206.856121ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:49:57.809292Z","caller":"traceutil/trace.go:171","msg":"trace[1119784809] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"149.655923ms","start":"2026-02-25T21:49:57.659607Z","end":"2026-02-25T21:49:57.809263Z","steps":["trace[1119784809] 'read index received' (duration: 149.556981ms)","trace[1119784809] 'applied index is now lower than readState.Index' (duration: 97.652µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:49:57.809372Z","caller":"traceutil/trace.go:171","msg":"trace[179302145] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"192.599445ms","start":"2026-02-25T21:49:57.616707Z","end":"2026-02-25T21:49:57.809306Z","steps":["trace[179302145] 'process raft request' (duration: 192.401481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:57.80945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.340236ms","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-25T21:49:57.80945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.407551ms","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-25T21:49:57.809488Z","caller":"traceutil/trace.go:171","msg":"trace[406224832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"316.420308ms","start":"2026-02-25T21:49:57.493055Z","end":"2026-02-25T21:49:57.809475Z","steps":["trace[406224832] 'agreement among raft nodes before linearized reading' (duration: 316.337766ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:49:57.809501Z","caller":"traceutil/trace.go:171","msg":"trace[2033964212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"185.514433ms","start":"2026-02-25T21:49:57.623973Z","end":"2026-02-25T21:49:57.809488Z","steps":["trace[2033964212] 'agreement among raft nodes before linearized reading' (duration: 185.342979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:57.809522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:49:57.49304Z","time spent":"316.473769ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T21:49:57.825089Z","caller":"traceutil/trace.go:171","msg":"trace[1075537675] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"160.721135ms","start":"2026-02-25T21:49:57.664352Z","end":"2026-02-25T21:49:57.825074Z","steps":["trace[1075537675] 'process raft request' (duration: 160.620213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:58.551115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.072147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T21:49:58.551208Z","caller":"traceutil/trace.go:171","msg":"trace[1001421080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"628.22127ms","start":"2026-02-25T21:49:57.922954Z","end":"2026-02-25T21:49:58.551175Z","steps":["trace[1001421080] 'range keys from in-memory index tree' (duration: 627.920624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:58.551307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:49:57.922921Z","time spent":"628.329003ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T21:49:58.599242Z","caller":"traceutil/trace.go:171","msg":"trace[1848165840] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"649.438375ms","start":"2026-02-25T21:49:57.949769Z","end":"2026-02-25T21:49:58.599208Z","steps":["trace[1848165840] 'process raft request' (duration: 649.2384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:58.599455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:49:57.949745Z","time spent":"649.591688ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:49:59.02208Z","caller":"traceutil/trace.go:171","msg":"trace[140554749] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"259.154228ms","start":"2026-02-25T21:49:58.762896Z","end":"2026-02-25T21:49:59.02205Z","steps":["trace[140554749] 'read index received' (duration: 189.436203ms)","trace[140554749] 'applied index is now lower than readState.Index' (duration: 69.716995ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:49:59.022207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.290902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:49:59.022182Z","caller":"traceutil/trace.go:171","msg":"trace[311085185] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"466.047161ms","start":"2026-02-25T21:49:58.556095Z","end":"2026-02-25T21:49:59.022143Z","steps":["trace[311085185] 'process raft request' (duration: 396.213743ms)","trace[311085185] 'compare' (duration: 69.620353ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:49:59.022241Z","caller":"traceutil/trace.go:171","msg":"trace[765786197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"259.384993ms","start":"2026-02-25T21:49:58.762846Z","end":"2026-02-25T21:49:59.022231Z","steps":["trace[765786197] 'agreement among raft nodes before linearized reading' (duration: 259.307721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:49:59.02241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:49:58.556048Z","time spent":"466.226145ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:50:00.624451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.424403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:50:00.62458Z","caller":"traceutil/trace.go:171","msg":"trace[1884308052] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:565; }","duration":"175.580877ms","start":"2026-02-25T21:50:00.448976Z","end":"2026-02-25T21:50:00.624557Z","steps":["trace[1884308052] 'count revisions from in-memory index tree' (duration: 175.378622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:00.62471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.218764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:50:00.624879Z","caller":"traceutil/trace.go:171","msg":"trace[963625806] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:565; }","duration":"156.408488ms","start":"2026-02-25T21:50:00.468448Z","end":"2026-02-25T21:50:00.624857Z","steps":["trace[963625806] 'range keys from in-memory index tree' (duration: 156.155233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:00.62509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.772041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:50:00.625196Z","caller":"traceutil/trace.go:171","msg":"trace[816842781] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:565; }","duration":"175.896224ms","start":"2026-02-25T21:50:00.449269Z","end":"2026-02-25T21:50:00.625166Z","steps":["trace[816842781] 'range keys from in-memory index tree' (duration: 175.71305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:00.624758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.542206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:50:00.625474Z","caller":"traceutil/trace.go:171","msg":"trace[470693453] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:565; }","duration":"176.350314ms","start":"2026-02-25T21:50:00.449091Z","end":"2026-02-25T21:50:00.625441Z","steps":["trace[470693453] 'range keys from in-memory index tree' (duration: 175.365822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:18.444447Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991639821,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:50:18.945591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991639821,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:50:18.954663Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.021572997s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T21:50:18.955021Z","caller":"traceutil/trace.go:171","msg":"trace[1454235529] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"1.010997405s","start":"2026-02-25T21:50:17.943999Z","end":"2026-02-25T21:50:18.954996Z","steps":["trace[1454235529] 'read index received' (duration: 1.010846592s)","trace[1454235529] 'applied index is now lower than readState.Index' (duration: 149.873µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:18.955074Z","caller":"traceutil/trace.go:171","msg":"trace[734611509] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"1.022036278s","start":"2026-02-25T21:50:17.933004Z","end":"2026-02-25T21:50:18.95504Z","steps":["trace[734611509] 'process raft request' (duration: 1.021842234s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:18.955247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:17.932981Z","time spent":"1.02215643s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:50:18.955264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011242331s","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-25T21:50:18.955308Z","caller":"traceutil/trace.go:171","msg":"trace[1564435376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:722; }","duration":"1.011375214s","start":"2026-02-25T21:50:17.943918Z","end":"2026-02-25T21:50:18.955293Z","steps":["trace[1564435376] 'agreement among raft nodes before linearized reading' (duration: 1.01120419s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:18.955352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:17.943882Z","time spent":"1.011461156s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T21:50:18.955372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006331128s","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-25T21:50:18.955438Z","caller":"traceutil/trace.go:171","msg":"trace[2073032661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:722; }","duration":"1.006437801s","start":"2026-02-25T21:50:17.948982Z","end":"2026-02-25T21:50:18.95542Z","steps":["trace[2073032661] 'agreement among raft nodes before linearized reading' (duration: 1.006268697s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:18.955468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:17.948972Z","time spent":"1.006490771s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T21:50:18.955494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.578938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:50:18.955528Z","caller":"traceutil/trace.go:171","msg":"trace[2019121549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:722; }","duration":"193.65468ms","start":"2026-02-25T21:50:18.761864Z","end":"2026-02-25T21:50:18.955519Z","steps":["trace[2019121549] 'agreement among raft nodes before linearized reading' (duration: 193.606969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:18.955614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.154616ms","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-25T21:50:18.955642Z","caller":"traceutil/trace.go:171","msg":"trace[414607440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:722; }","duration":"582.219966ms","start":"2026-02-25T21:50:18.373416Z","end":"2026-02-25T21:50:18.955636Z","steps":["trace[414607440] 'agreement among raft nodes before linearized reading' (duration: 582.135474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:18.95566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:18.373395Z","time spent":"582.261448ms","remote":"127.0.0.1:35590","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-25T21:50:19.616652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991639824,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T21:50:19.838805Z","caller":"traceutil/trace.go:171","msg":"trace[66427189] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"879.16965ms","start":"2026-02-25T21:50:18.95961Z","end":"2026-02-25T21:50:19.83878Z","steps":["trace[66427189] 'process raft request' (duration: 879.001996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:19.838976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:18.95959Z","time spent":"879.294013ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:50:19.972858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.007871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T21:50:19.973181Z","caller":"traceutil/trace.go:171","msg":"trace[590112485] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"1.011482866s","start":"2026-02-25T21:50:18.961677Z","end":"2026-02-25T21:50:19.97316Z","steps":["trace[590112485] 'process raft request' (duration: 1.011299342s)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:19.973182Z","caller":"traceutil/trace.go:171","msg":"trace[1885891255] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"1.011735413s","start":"2026-02-25T21:50:18.961396Z","end":"2026-02-25T21:50:19.973131Z","steps":["trace[1885891255] 'process raft request' (duration: 906.100096ms)","trace[1885891255] 'compare' (duration: 104.900089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:50:19.973281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:18.961659Z","time spent":"1.011571058s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:50:19.9733Z","caller":"traceutil/trace.go:171","msg":"trace[1672528454] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:749; }","duration":"857.642008ms","start":"2026-02-25T21:50:19.115627Z","end":"2026-02-25T21:50:19.973269Z","steps":["trace[1672528454] 'read index received' (duration: 723.041679ms)","trace[1672528454] 'applied index is now lower than readState.Index' (duration: 134.597689ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:19.973382Z","caller":"traceutil/trace.go:171","msg":"trace[1330859881] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"886.034018ms","start":"2026-02-25T21:50:19.087328Z","end":"2026-02-25T21:50:19.973362Z","steps":["trace[1330859881] 'process raft request' (duration: 885.767882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:19.973434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:18.961372Z","time spent":"1.011913367s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:50:19.973508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.884094ms","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-25T21:50:19.973506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:19.087303Z","time spent":"886.13876ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:50:19.973543Z","caller":"traceutil/trace.go:171","msg":"trace[725857384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"857.955066ms","start":"2026-02-25T21:50:19.115576Z","end":"2026-02-25T21:50:19.973531Z","steps":["trace[725857384] 'agreement among raft nodes before linearized reading' (duration: 857.824893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:19.973578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:19.115558Z","time spent":"858.013497ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T21:50:19.973574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.112735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:50:19.97366Z","caller":"traceutil/trace.go:171","msg":"trace[1976794919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"213.243467ms","start":"2026-02-25T21:50:19.760395Z","end":"2026-02-25T21:50:19.973639Z","steps":["trace[1976794919] 'agreement among raft nodes before linearized reading' (duration: 213.092814ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:21.309241Z","caller":"traceutil/trace.go:171","msg":"trace[1227907575] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"547.090925ms","start":"2026-02-25T21:50:20.762129Z","end":"2026-02-25T21:50:21.30922Z","steps":["trace[1227907575] 'read index received' (duration: 468.482646ms)","trace[1227907575] 'applied index is now lower than readState.Index' (duration: 78.607049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:50:21.309446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.298379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:50:21.309538Z","caller":"traceutil/trace.go:171","msg":"trace[1210796542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"547.458142ms","start":"2026-02-25T21:50:20.762059Z","end":"2026-02-25T21:50:21.309518Z","steps":["trace[1210796542] 'agreement among raft nodes before linearized reading' (duration: 547.265178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:21.309592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:20.762031Z","time spent":"547.551835ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T21:50:21.309479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.812686ms","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-25T21:50:21.309637Z","caller":"traceutil/trace.go:171","msg":"trace[180872170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"348.036162ms","start":"2026-02-25T21:50:20.96159Z","end":"2026-02-25T21:50:21.309626Z","steps":["trace[180872170] 'agreement among raft nodes before linearized reading' (duration: 347.775246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:21.309679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:20.961539Z","time spent":"348.127824ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T21:50:22.969882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.217212ms","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-25T21:50:22.969903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.322935ms","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-25T21:50:22.969898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126159279s","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-25T21:50:22.969962Z","caller":"traceutil/trace.go:171","msg":"trace[1011352495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"991.328235ms","start":"2026-02-25T21:50:21.978615Z","end":"2026-02-25T21:50:22.969943Z","steps":["trace[1011352495] 'range keys from in-memory index tree' (duration: 991.059898ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:22.969974Z","caller":"traceutil/trace.go:171","msg":"trace[1730903154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"991.456068ms","start":"2026-02-25T21:50:21.9785Z","end":"2026-02-25T21:50:22.969956Z","steps":["trace[1730903154] 'range keys from in-memory index tree' (duration: 991.057228ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:22.970008Z","caller":"traceutil/trace.go:171","msg":"trace[1631215021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"1.126334713s","start":"2026-02-25T21:50:21.843649Z","end":"2026-02-25T21:50:22.969984Z","steps":["trace[1631215021] 'range keys from in-memory index tree' (duration: 1.125887763s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:22.97002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:21.97847Z","time spent":"991.54109ms","remote":"127.0.0.1:35590","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-25T21:50:22.970019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:21.97861Z","time spent":"991.396777ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T21:50:22.970065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:21.843631Z","time spent":"1.126424805s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T21:50:22.970143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.041265ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:50:22.970275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.410702ms","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-25T21:50:22.970309Z","caller":"traceutil/trace.go:171","msg":"trace[939571204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"965.457562ms","start":"2026-02-25T21:50:22.004843Z","end":"2026-02-25T21:50:22.9703Z","steps":["trace[939571204] 'range keys from in-memory index tree' (duration: 965.217577ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:22.970304Z","caller":"traceutil/trace.go:171","msg":"trace[250154917] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:728; }","duration":"816.215819ms","start":"2026-02-25T21:50:22.15406Z","end":"2026-02-25T21:50:22.970276Z","steps":["trace[250154917] 'range keys from in-memory index tree' (duration: 816.014064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:22.970337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:22.004836Z","time spent":"965.494054ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T21:50:22.970477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.898626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:50:22.970515Z","caller":"traceutil/trace.go:171","msg":"trace[212051396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"207.937277ms","start":"2026-02-25T21:50:22.762566Z","end":"2026-02-25T21:50:22.970503Z","steps":["trace[212051396] 'range keys from in-memory index tree' (duration: 207.737263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:22.970614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.760957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T21:50:22.970646Z","caller":"traceutil/trace.go:171","msg":"trace[1793543170] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:728; }","duration":"400.860089ms","start":"2026-02-25T21:50:22.569776Z","end":"2026-02-25T21:50:22.970636Z","steps":["trace[1793543170] 'count revisions from in-memory index tree' (duration: 400.573483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:22.970673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:22.569754Z","time spent":"400.911361ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-02-25T21:50:23.145886Z","caller":"traceutil/trace.go:171","msg":"trace[1103509448] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"170.459459ms","start":"2026-02-25T21:50:22.975402Z","end":"2026-02-25T21:50:23.145862Z","steps":["trace[1103509448] 'process raft request' (duration: 170.298986ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:23.148457Z","caller":"traceutil/trace.go:171","msg":"trace[641032204] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"168.639307ms","start":"2026-02-25T21:50:22.979793Z","end":"2026-02-25T21:50:23.148432Z","steps":["trace[641032204] 'process raft request' (duration: 168.594506ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:23.148497Z","caller":"traceutil/trace.go:171","msg":"trace[760744658] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"172.800483ms","start":"2026-02-25T21:50:22.975658Z","end":"2026-02-25T21:50:23.148458Z","steps":["trace[760744658] 'process raft request' (duration: 172.555137ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:23.148483Z","caller":"traceutil/trace.go:171","msg":"trace[2122507136] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"170.717425ms","start":"2026-02-25T21:50:22.977742Z","end":"2026-02-25T21:50:23.14846Z","steps":["trace[2122507136] 'process raft request' (duration: 170.601372ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:25.008104Z","caller":"traceutil/trace.go:171","msg":"trace[1481375622] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"247.111802ms","start":"2026-02-25T21:50:24.760972Z","end":"2026-02-25T21:50:25.008084Z","steps":["trace[1481375622] 'read index received' (duration: 247.055211ms)","trace[1481375622] 'applied index is now lower than readState.Index' (duration: 55.111µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:25.008183Z","caller":"traceutil/trace.go:171","msg":"trace[476644370] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"340.761485ms","start":"2026-02-25T21:50:24.667399Z","end":"2026-02-25T21:50:25.008161Z","steps":["trace[476644370] 'process raft request' (duration: 340.55412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:25.00822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.230525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:50:25.008247Z","caller":"traceutil/trace.go:171","msg":"trace[1013986776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"247.308747ms","start":"2026-02-25T21:50:24.760929Z","end":"2026-02-25T21:50:25.008238Z","steps":["trace[1013986776] 'agreement among raft nodes before linearized reading' (duration: 247.221825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:25.008328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:24.66738Z","time spent":"340.867487ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:50:29.359722Z","caller":"traceutil/trace.go:171","msg":"trace[1004814114] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"150.214346ms","start":"2026-02-25T21:50:29.209482Z","end":"2026-02-25T21:50:29.359696Z","steps":["trace[1004814114] 'read index received' (duration: 62.28981ms)","trace[1004814114] 'applied index is now lower than readState.Index' (duration: 87.923026ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:29.359857Z","caller":"traceutil/trace.go:171","msg":"trace[277864408] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"146.393687ms","start":"2026-02-25T21:50:29.213451Z","end":"2026-02-25T21:50:29.359844Z","steps":["trace[277864408] 'process raft request' (duration: 146.194123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:29.360077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.069112ms","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-25T21:50:29.360173Z","caller":"traceutil/trace.go:171","msg":"trace[52963490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"174.220346ms","start":"2026-02-25T21:50:29.18594Z","end":"2026-02-25T21:50:29.360161Z","steps":["trace[52963490] 'agreement among raft nodes before linearized reading' (duration: 173.864598ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:29.359806Z","caller":"traceutil/trace.go:171","msg":"trace[583361463] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"174.959273ms","start":"2026-02-25T21:50:29.184806Z","end":"2026-02-25T21:50:29.359766Z","steps":["trace[583361463] 'process raft request' (duration: 86.945934ms)","trace[583361463] 'compare' (duration: 87.764023ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:29.497752Z","caller":"traceutil/trace.go:171","msg":"trace[1776764959] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"128.593404ms","start":"2026-02-25T21:50:29.369129Z","end":"2026-02-25T21:50:29.497723Z","steps":["trace[1776764959] 'process raft request' (duration: 127.628531ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:31.517526Z","caller":"traceutil/trace.go:171","msg":"trace[916536434] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"129.654498ms","start":"2026-02-25T21:50:31.387845Z","end":"2026-02-25T21:50:31.5175Z","steps":["trace[916536434] 'process raft request' (duration: 129.579457ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:31.517549Z","caller":"traceutil/trace.go:171","msg":"trace[1415891240] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"130.637611ms","start":"2026-02-25T21:50:31.386865Z","end":"2026-02-25T21:50:31.517503Z","steps":["trace[1415891240] 'process raft request' (duration: 129.990496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:45.582137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.685506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T21:50:45.582282Z","caller":"traceutil/trace.go:171","msg":"trace[1808407290] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"268.870109ms","start":"2026-02-25T21:50:45.313391Z","end":"2026-02-25T21:50:45.582261Z","steps":["trace[1808407290] 'read index received' (duration: 17.838825ms)","trace[1808407290] 'applied index is now lower than readState.Index' (duration: 251.029224ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:45.582305Z","caller":"traceutil/trace.go:171","msg":"trace[1901307046] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"314.815968ms","start":"2026-02-25T21:50:45.267448Z","end":"2026-02-25T21:50:45.582264Z","steps":["trace[1901307046] 'process raft request' (duration: 63.876947ms)","trace[1901307046] 'compare' (duration: 250.327657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:50:45.582518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:45.267429Z","time spent":"314.970412ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:50:45.582587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.206147ms","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-25T21:50:45.582626Z","caller":"traceutil/trace.go:171","msg":"trace[1696675210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"269.33501ms","start":"2026-02-25T21:50:45.31328Z","end":"2026-02-25T21:50:45.582615Z","steps":["trace[1696675210] 'agreement among raft nodes before linearized reading' (duration: 269.088554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:46.083032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991640031,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T21:50:46.336209Z","caller":"traceutil/trace.go:171","msg":"trace[1953744721] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"1.000535081s","start":"2026-02-25T21:50:45.335648Z","end":"2026-02-25T21:50:46.336183Z","steps":["trace[1953744721] 'process raft request' (duration: 997.283966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:46.336371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:45.335626Z","time spent":"1.000655844s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:50:46.340871Z","caller":"traceutil/trace.go:171","msg":"trace[2030614619] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:830; }","duration":"758.542718ms","start":"2026-02-25T21:50:45.58231Z","end":"2026-02-25T21:50:46.340853Z","steps":["trace[2030614619] 'read index received' (duration: 750.794248ms)","trace[2030614619] 'applied index is now lower than readState.Index' (duration: 7.74724ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:46.341027Z","caller":"traceutil/trace.go:171","msg":"trace[1584518040] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"754.028062ms","start":"2026-02-25T21:50:45.586927Z","end":"2026-02-25T21:50:46.340955Z","steps":["trace[1584518040] 'process raft request' (duration: 753.820887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:46.34108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.650754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-25T21:50:46.341126Z","caller":"traceutil/trace.go:171","msg":"trace[49771477] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:798; }","duration":"756.739776ms","start":"2026-02-25T21:50:45.584374Z","end":"2026-02-25T21:50:46.341113Z","steps":["trace[49771477] 'agreement among raft nodes before linearized reading' (duration: 756.659804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:46.341165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:45.584352Z","time spent":"756.805728ms","remote":"127.0.0.1:35496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T21:50:46.341155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.77846ms","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-25T21:50:46.341262Z","caller":"traceutil/trace.go:171","msg":"trace[1439401556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"760.965034ms","start":"2026-02-25T21:50:45.580272Z","end":"2026-02-25T21:50:46.341237Z","steps":["trace[1439401556] 'agreement among raft nodes before linearized reading' (duration: 760.626996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:46.341291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:45.586903Z","time spent":"754.214987ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:50:46.341328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:45.580245Z","time spent":"761.068616ms","remote":"127.0.0.1:35590","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-25T21:50:46.341275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.865565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T21:50:46.341363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.375433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:50:46.341435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.817536ms","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-25T21:50:46.341474Z","caller":"traceutil/trace.go:171","msg":"trace[1485995257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"707.894628ms","start":"2026-02-25T21:50:45.633569Z","end":"2026-02-25T21:50:46.341463Z","steps":["trace[1485995257] 'agreement among raft nodes before linearized reading' (duration: 707.746154ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:46.341484Z","caller":"traceutil/trace.go:171","msg":"trace[1289463464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"578.612349ms","start":"2026-02-25T21:50:45.762846Z","end":"2026-02-25T21:50:46.341458Z","steps":["trace[1289463464] 'agreement among raft nodes before linearized reading' (duration: 578.365613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:46.341508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:45.63355Z","time spent":"707.95262ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T21:50:46.341543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:45.762746Z","time spent":"578.786482ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T21:50:46.341447Z","caller":"traceutil/trace.go:171","msg":"trace[333410119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"878.08305ms","start":"2026-02-25T21:50:45.463352Z","end":"2026-02-25T21:50:46.341435Z","steps":["trace[333410119] 'agreement among raft nodes before linearized reading' (duration: 877.65299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:46.34172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:45.463255Z","time spent":"878.450778ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T21:50:46.341958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.22915ms","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-25T21:50:46.342093Z","caller":"traceutil/trace.go:171","msg":"trace[1478378899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"770.413634ms","start":"2026-02-25T21:50:45.571626Z","end":"2026-02-25T21:50:46.34204Z","steps":["trace[1478378899] 'agreement among raft nodes before linearized reading' (duration: 769.36228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:46.342316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:45.571572Z","time spent":"770.731812ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T21:50:46.457507Z","caller":"traceutil/trace.go:171","msg":"trace[589329557] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"111.202499ms","start":"2026-02-25T21:50:46.346269Z","end":"2026-02-25T21:50:46.457471Z","steps":["trace[589329557] 'process raft request' (duration: 92.591375ms)","trace[589329557] 'compare' (duration: 18.383879ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:46.457812Z","caller":"traceutil/trace.go:171","msg":"trace[1419304678] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"111.027615ms","start":"2026-02-25T21:50:46.346749Z","end":"2026-02-25T21:50:46.457777Z","steps":["trace[1419304678] 'process raft request' (duration: 110.675797ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:46.458018Z","caller":"traceutil/trace.go:171","msg":"trace[169271616] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"110.743548ms","start":"2026-02-25T21:50:46.347247Z","end":"2026-02-25T21:50:46.45799Z","steps":["trace[169271616] 'process raft request' (duration: 110.435531ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:48.85615Z","caller":"traceutil/trace.go:171","msg":"trace[795026790] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"447.054918ms","start":"2026-02-25T21:50:48.409065Z","end":"2026-02-25T21:50:48.85612Z","steps":["trace[795026790] 'read index received' (duration: 446.833863ms)","trace[795026790] 'applied index is now lower than readState.Index' (duration: 219.315µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:48.856224Z","caller":"traceutil/trace.go:171","msg":"trace[1487445276] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"504.359091ms","start":"2026-02-25T21:50:48.351816Z","end":"2026-02-25T21:50:48.856175Z","steps":["trace[1487445276] 'process raft request' (duration: 504.105985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:48.856557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.455566ms","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-25T21:50:48.856642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.526334ms","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-25T21:50:48.856676Z","caller":"traceutil/trace.go:171","msg":"trace[1526764081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"447.664872ms","start":"2026-02-25T21:50:48.40898Z","end":"2026-02-25T21:50:48.856644Z","steps":["trace[1526764081] 'agreement among raft nodes before linearized reading' (duration: 447.268933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:48.85665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:48.351795Z","time spent":"504.692508ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:50:48.856746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:48.408946Z","time spent":"447.785784ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T21:50:48.856772Z","caller":"traceutil/trace.go:171","msg":"trace[1974239536] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"394.646256ms","start":"2026-02-25T21:50:48.462031Z","end":"2026-02-25T21:50:48.856677Z","steps":["trace[1974239536] 'agreement among raft nodes before linearized reading' (duration: 394.481823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:48.856722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.606235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T21:50:48.856896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:48.462008Z","time spent":"394.873462ms","remote":"127.0.0.1:35590","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-25T21:50:48.856978Z","caller":"traceutil/trace.go:171","msg":"trace[1432075687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"394.833281ms","start":"2026-02-25T21:50:48.462049Z","end":"2026-02-25T21:50:48.856883Z","steps":["trace[1432075687] 'agreement among raft nodes before linearized reading' (duration: 394.290529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:48.856752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.450598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T21:50:48.857045Z","caller":"traceutil/trace.go:171","msg":"trace[1707377519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:803; }","duration":"393.891178ms","start":"2026-02-25T21:50:48.463136Z","end":"2026-02-25T21:50:48.857028Z","steps":["trace[1707377519] 'agreement among raft nodes before linearized reading' (duration: 393.207263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:48.857127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:48.462034Z","time spent":"394.997904ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T21:50:48.857142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:50:48.463114Z","time spent":"393.973421ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T21:50:55.068188Z","caller":"traceutil/trace.go:171","msg":"trace[1978335941] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"171.106223ms","start":"2026-02-25T21:50:54.897067Z","end":"2026-02-25T21:50:55.068173Z","steps":["trace[1978335941] 'read index received' (duration: 171.024291ms)","trace[1978335941] 'applied index is now lower than readState.Index' (duration: 81.392µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:50:55.06827Z","caller":"traceutil/trace.go:171","msg":"trace[1159899701] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"178.516534ms","start":"2026-02-25T21:50:54.889747Z","end":"2026-02-25T21:50:55.068264Z","steps":["trace[1159899701] 'process raft request' (duration: 178.34241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:55.068432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.359549ms","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-25T21:50:55.068451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.874085ms","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-25T21:50:55.068431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.718877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T21:50:55.068481Z","caller":"traceutil/trace.go:171","msg":"trace[1697002063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:819; }","duration":"151.941628ms","start":"2026-02-25T21:50:54.916527Z","end":"2026-02-25T21:50:55.068468Z","steps":["trace[1697002063] 'agreement among raft nodes before linearized reading' (duration: 151.872835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:55.068501Z","caller":"traceutil/trace.go:171","msg":"trace[1592271877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:819; }","duration":"153.82114ms","start":"2026-02-25T21:50:54.914664Z","end":"2026-02-25T21:50:55.068485Z","steps":["trace[1592271877] 'agreement among raft nodes before linearized reading' (duration: 153.617055ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:55.068458Z","caller":"traceutil/trace.go:171","msg":"trace[2065983668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"171.445231ms","start":"2026-02-25T21:50:54.897007Z","end":"2026-02-25T21:50:55.068452Z","steps":["trace[2065983668] 'agreement among raft nodes before linearized reading' (duration: 171.359599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:50:55.068706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.758309ms","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-25T21:50:55.068801Z","caller":"traceutil/trace.go:171","msg":"trace[1521270254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:819; }","duration":"150.866832ms","start":"2026-02-25T21:50:54.91792Z","end":"2026-02-25T21:50:55.068787Z","steps":["trace[1521270254] 'agreement among raft nodes before linearized reading' (duration: 150.408511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:55.317075Z","caller":"traceutil/trace.go:171","msg":"trace[1481424997] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"244.105452ms","start":"2026-02-25T21:50:55.072946Z","end":"2026-02-25T21:50:55.317051Z","steps":["trace[1481424997] 'process raft request' (duration: 243.606851ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:55.317433Z","caller":"traceutil/trace.go:171","msg":"trace[1354715979] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"243.054898ms","start":"2026-02-25T21:50:55.074363Z","end":"2026-02-25T21:50:55.317418Z","steps":["trace[1354715979] 'process raft request' (duration: 242.788491ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:50:55.317263Z","caller":"traceutil/trace.go:171","msg":"trace[1415773227] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"244.085091ms","start":"2026-02-25T21:50:55.073145Z","end":"2026-02-25T21:50:55.31723Z","steps":["trace[1415773227] 'process raft request' (duration: 243.826045ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:00.055935Z","caller":"traceutil/trace.go:171","msg":"trace[1138052533] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"252.634542ms","start":"2026-02-25T21:50:59.803263Z","end":"2026-02-25T21:51:00.055897Z","steps":["trace[1138052533] 'process raft request' (duration: 252.399286ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:00.404774Z","caller":"traceutil/trace.go:171","msg":"trace[175771084] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"154.190489ms","start":"2026-02-25T21:51:00.250553Z","end":"2026-02-25T21:51:00.404744Z","steps":["trace[175771084] 'process raft request' (duration: 128.91922ms)","trace[175771084] 'compare' (duration: 25.040983ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:00.404761Z","caller":"traceutil/trace.go:171","msg":"trace[516910973] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"153.990033ms","start":"2026-02-25T21:51:00.250745Z","end":"2026-02-25T21:51:00.404736Z","steps":["trace[516910973] 'process raft request' (duration: 153.921162ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:00.655463Z","caller":"traceutil/trace.go:171","msg":"trace[1284058599] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"203.419326ms","start":"2026-02-25T21:51:00.452011Z","end":"2026-02-25T21:51:00.655431Z","steps":["trace[1284058599] 'process raft request' (duration: 154.841846ms)","trace[1284058599] 'compare' (duration: 48.432297ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:00.657795Z","caller":"traceutil/trace.go:171","msg":"trace[793683479] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"204.721287ms","start":"2026-02-25T21:51:00.453051Z","end":"2026-02-25T21:51:00.657772Z","steps":["trace[793683479] 'process raft request' (duration: 204.353069ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:00.836911Z","caller":"traceutil/trace.go:171","msg":"trace[1495022737] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"113.097723ms","start":"2026-02-25T21:51:00.723785Z","end":"2026-02-25T21:51:00.836883Z","steps":["trace[1495022737] 'process raft request' (duration: 100.722734ms)","trace[1495022737] 'compare' (duration: 12.242506ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:00.957877Z","caller":"traceutil/trace.go:171","msg":"trace[1432299265] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"232.066871ms","start":"2026-02-25T21:51:00.725778Z","end":"2026-02-25T21:51:00.957845Z","steps":["trace[1432299265] 'process raft request' (duration: 231.781005ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:00.957826Z","caller":"traceutil/trace.go:171","msg":"trace[954866051] linearizableReadLoop","detail":"{readStateIndex:891; appliedIndex:889; }","duration":"196.743808ms","start":"2026-02-25T21:51:00.761057Z","end":"2026-02-25T21:51:00.9578Z","steps":["trace[954866051] 'read index received' (duration: 63.498597ms)","trace[954866051] 'applied index is now lower than readState.Index' (duration: 133.244241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:51:00.958429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.348532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:51:00.958577Z","caller":"traceutil/trace.go:171","msg":"trace[252683948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:853; }","duration":"197.496666ms","start":"2026-02-25T21:51:00.760996Z","end":"2026-02-25T21:51:00.958492Z","steps":["trace[252683948] 'agreement among raft nodes before linearized reading' (duration: 196.941193ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:00.970419Z","caller":"traceutil/trace.go:171","msg":"trace[336248778] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"127.545248ms","start":"2026-02-25T21:51:00.842858Z","end":"2026-02-25T21:51:00.970403Z","steps":["trace[336248778] 'process raft request' (duration: 127.500807ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:00.970745Z","caller":"traceutil/trace.go:171","msg":"trace[2125268440] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"128.571151ms","start":"2026-02-25T21:51:00.842152Z","end":"2026-02-25T21:51:00.970723Z","steps":["trace[2125268440] 'process raft request' (duration: 128.124651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:01.590784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.012096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T21:51:01.590903Z","caller":"traceutil/trace.go:171","msg":"trace[1680035102] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"223.271407ms","start":"2026-02-25T21:51:01.367614Z","end":"2026-02-25T21:51:01.590885Z","steps":["trace[1680035102] 'read index received' (duration: 20.067066ms)","trace[1680035102] 'applied index is now lower than readState.Index' (duration: 203.203211ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:01.59095Z","caller":"traceutil/trace.go:171","msg":"trace[1176654239] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"223.577205ms","start":"2026-02-25T21:51:01.367332Z","end":"2026-02-25T21:51:01.590909Z","steps":["trace[1176654239] 'process raft request' (duration: 20.333223ms)","trace[1176654239] 'compare' (duration: 202.867003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:51:01.591145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.509183ms","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-25T21:51:01.591191Z","caller":"traceutil/trace.go:171","msg":"trace[1822328218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:887; }","duration":"223.616766ms","start":"2026-02-25T21:51:01.36756Z","end":"2026-02-25T21:51:01.591177Z","steps":["trace[1822328218] 'agreement among raft nodes before linearized reading' (duration: 223.37505ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:03.5113Z","caller":"traceutil/trace.go:171","msg":"trace[1612339957] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"141.550455ms","start":"2026-02-25T21:51:03.369726Z","end":"2026-02-25T21:51:03.511276Z","steps":["trace[1612339957] 'process raft request' (duration: 141.424762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:04.025634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.689718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:51:04.02566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.461606ms","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-25T21:51:04.025733Z","caller":"traceutil/trace.go:171","msg":"trace[1757129542] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:895; }","duration":"270.592319ms","start":"2026-02-25T21:51:03.755123Z","end":"2026-02-25T21:51:04.025716Z","steps":["trace[1757129542] 'count revisions from in-memory index tree' (duration: 270.371984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:04.025925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.250614ms","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-25T21:51:04.025979Z","caller":"traceutil/trace.go:171","msg":"trace[179707807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"263.78545ms","start":"2026-02-25T21:51:03.7619Z","end":"2026-02-25T21:51:04.025685Z","steps":["trace[179707807] 'range keys from in-memory index tree' (duration: 263.624827ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:04.02603Z","caller":"traceutil/trace.go:171","msg":"trace[767383602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"396.358036ms","start":"2026-02-25T21:51:03.629637Z","end":"2026-02-25T21:51:04.025996Z","steps":["trace[767383602] 'range keys from in-memory index tree' (duration: 396.022109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:04.026067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:03.629626Z","time spent":"396.433988ms","remote":"127.0.0.1:35590","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-25T21:51:04.025673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.201545ms","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-25T21:51:04.026589Z","caller":"traceutil/trace.go:171","msg":"trace[1446882029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"431.157297ms","start":"2026-02-25T21:51:03.595409Z","end":"2026-02-25T21:51:04.026567Z","steps":["trace[1446882029] 'range keys from in-memory index tree' (duration: 429.604191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:04.02661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:03.595361Z","time spent":"431.242989ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T21:51:08.547014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991640288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:51:09.047166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991640288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:51:09.547982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991640288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:51:10.048511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991640288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:51:10.549142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991640288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:51:10.762234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000292264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T21:51:10.762327Z","caller":"traceutil/trace.go:171","msg":"trace[1060762132] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000473198s","start":"2026-02-25T21:51:08.761833Z","end":"2026-02-25T21:51:10.762306Z","steps":["trace[1060762132] 'agreement among raft nodes before linearized reading' (duration: 2.000325235s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:10.762383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:08.761809Z","time spent":"2.00056334s","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T21:51:11.049894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991640288,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:51:11.146278Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.616965457s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T21:51:11.14663Z","caller":"traceutil/trace.go:171","msg":"trace[1393967869] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"3.617388987s","start":"2026-02-25T21:51:07.529217Z","end":"2026-02-25T21:51:11.146606Z","steps":["trace[1393967869] 'process raft request' (duration: 3.617233484s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.1468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:07.529197Z","time spent":"3.617525551s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:11.835925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.424479ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T21:51:11.836154Z","caller":"traceutil/trace.go:171","msg":"trace[1278663968] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:946; }","duration":"3.789450953s","start":"2026-02-25T21:51:08.046687Z","end":"2026-02-25T21:51:11.836138Z","steps":["trace[1278663968] 'read index received' (duration: 3.099864641s)","trace[1278663968] 'applied index is now lower than readState.Index' (duration: 689.585302ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:11.836254Z","caller":"traceutil/trace.go:171","msg":"trace[660945657] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"960.928239ms","start":"2026-02-25T21:51:10.875317Z","end":"2026-02-25T21:51:11.836245Z","steps":["trace[660945657] 'process raft request' (duration: 960.706604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.836347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:10.875291Z","time spent":"960.993301ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:11.836633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201605931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:51:11.83661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.786555976s","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-25T21:51:11.836676Z","caller":"traceutil/trace.go:171","msg":"trace[1811266421] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:908; }","duration":"1.201683753s","start":"2026-02-25T21:51:10.634982Z","end":"2026-02-25T21:51:11.836665Z","steps":["trace[1811266421] 'agreement among raft nodes before linearized reading' (duration: 1.201616202s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.836707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:10.634957Z","time spent":"1.201742935s","remote":"127.0.0.1:35544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-02-25T21:51:11.836711Z","caller":"traceutil/trace.go:171","msg":"trace[30720944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:908; }","duration":"3.78670949s","start":"2026-02-25T21:51:08.04998Z","end":"2026-02-25T21:51:11.836689Z","steps":["trace[30720944] 'agreement among raft nodes before linearized reading' (duration: 3.786484365s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.836755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:08.049952Z","time spent":"3.786793121s","remote":"127.0.0.1:35590","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-25T21:51:11.836937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.614369718s","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-25T21:51:11.836938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.576079487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T21:51:11.836977Z","caller":"traceutil/trace.go:171","msg":"trace[1751807233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:908; }","duration":"2.61444409s","start":"2026-02-25T21:51:09.222521Z","end":"2026-02-25T21:51:11.836965Z","steps":["trace[1751807233] 'agreement among raft nodes before linearized reading' (duration: 2.614350818s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.836984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.037828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:51:11.837008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:09.222496Z","time spent":"2.614506222s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T21:51:11.837013Z","caller":"traceutil/trace.go:171","msg":"trace[47422010] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:908; }","duration":"2.57620629s","start":"2026-02-25T21:51:09.260786Z","end":"2026-02-25T21:51:11.836992Z","steps":["trace[47422010] 'agreement among raft nodes before linearized reading' (duration: 2.576076027s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.837049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.603812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:51:11.837064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:09.260766Z","time spent":"2.576285152s","remote":"127.0.0.1:35746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-25T21:51:11.837083Z","caller":"traceutil/trace.go:171","msg":"trace[327328798] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:908; }","duration":"878.636553ms","start":"2026-02-25T21:51:10.958438Z","end":"2026-02-25T21:51:11.837074Z","steps":["trace[327328798] 'agreement among raft nodes before linearized reading' (duration: 878.593282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.837112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:10.958386Z","time spent":"878.718974ms","remote":"127.0.0.1:35654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T21:51:11.837129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.625392335s","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-25T21:51:11.83716Z","caller":"traceutil/trace.go:171","msg":"trace[1579077793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"2.625452846s","start":"2026-02-25T21:51:09.211698Z","end":"2026-02-25T21:51:11.837151Z","steps":["trace[1579077793] 'agreement among raft nodes before linearized reading' (duration: 2.625379675s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.837193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:09.211681Z","time spent":"2.625506168s","remote":"127.0.0.1:35590","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-25T21:51:11.837061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351868359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:51:11.837256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.339062131s","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":"warn","ts":"2026-02-25T21:51:11.837294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170586169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:51:11.837299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.429148866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T21:51:11.837261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074252807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:51:11.837318Z","caller":"traceutil/trace.go:171","msg":"trace[525359677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"1.17063386s","start":"2026-02-25T21:51:10.666677Z","end":"2026-02-25T21:51:11.837311Z","steps":["trace[525359677] 'agreement among raft nodes before linearized reading' (duration: 1.170601449s)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:11.837379Z","caller":"traceutil/trace.go:171","msg":"trace[538194378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"1.07439916s","start":"2026-02-25T21:51:10.762971Z","end":"2026-02-25T21:51:11.83737Z","steps":["trace[538194378] 'agreement among raft nodes before linearized reading' (duration: 1.074269777s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.837388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:10.666658Z","time spent":"1.170724342s","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T21:51:11.837444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:10.762953Z","time spent":"1.074481892s","remote":"127.0.0.1:35330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T21:51:11.837276Z","caller":"traceutil/trace.go:171","msg":"trace[1807794409] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:908; }","duration":"1.352237588s","start":"2026-02-25T21:51:10.485015Z","end":"2026-02-25T21:51:11.837253Z","steps":["trace[1807794409] 'agreement among raft nodes before linearized reading' (duration: 1.351852109s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.837556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:10.484997Z","time spent":"1.352540905s","remote":"127.0.0.1:35494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-25T21:51:11.837014Z","caller":"traceutil/trace.go:171","msg":"trace[130232802] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:908; }","duration":"658.10085ms","start":"2026-02-25T21:51:11.178904Z","end":"2026-02-25T21:51:11.837005Z","steps":["trace[130232802] 'agreement among raft nodes before linearized reading' (duration: 658.060939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.837645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:11.178857Z","time spent":"658.776126ms","remote":"127.0.0.1:35462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-02-25T21:51:11.837309Z","caller":"traceutil/trace.go:171","msg":"trace[1206435691] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:908; }","duration":"2.339145923s","start":"2026-02-25T21:51:09.498146Z","end":"2026-02-25T21:51:11.837292Z","steps":["trace[1206435691] 'agreement among raft nodes before linearized reading' (duration: 2.339079121s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.837788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:09.498122Z","time spent":"2.339647535s","remote":"127.0.0.1:53872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-25T21:51:11.837375Z","caller":"traceutil/trace.go:171","msg":"trace[40790710] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:908; }","duration":"3.429256478s","start":"2026-02-25T21:51:08.408108Z","end":"2026-02-25T21:51:11.837364Z","steps":["trace[40790710] 'agreement among raft nodes before linearized reading' (duration: 3.429143936s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.837918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:08.408091Z","time spent":"3.429808683s","remote":"127.0.0.1:35828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T21:51:11.836946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.790264433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T21:51:11.838064Z","caller":"traceutil/trace.go:171","msg":"trace[516344432] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"3.79142023s","start":"2026-02-25T21:51:08.046633Z","end":"2026-02-25T21:51:11.838053Z","steps":["trace[516344432] 'agreement among raft nodes before linearized reading' (duration: 3.790207871s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:11.838099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:08.04661Z","time spent":"3.791480521s","remote":"127.0.0.1:35590","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-25T21:51:11.953404Z","caller":"traceutil/trace.go:171","msg":"trace[862249805] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"110.663937ms","start":"2026-02-25T21:51:11.842637Z","end":"2026-02-25T21:51:11.953301Z","steps":["trace[862249805] 'process raft request' (duration: 69.374506ms)","trace[862249805] 'compare' (duration: 41.152818ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:11.959057Z","caller":"traceutil/trace.go:171","msg":"trace[1100611821] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"114.521306ms","start":"2026-02-25T21:51:11.844518Z","end":"2026-02-25T21:51:11.959039Z","steps":["trace[1100611821] 'process raft request' (duration: 114.417854ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:11.95911Z","caller":"traceutil/trace.go:171","msg":"trace[1860356330] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"113.200145ms","start":"2026-02-25T21:51:11.845876Z","end":"2026-02-25T21:51:11.959076Z","steps":["trace[1860356330] 'process raft request' (duration: 113.105193ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:11.95908Z","caller":"traceutil/trace.go:171","msg":"trace[97048928] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:950; }","duration":"112.724774ms","start":"2026-02-25T21:51:11.846303Z","end":"2026-02-25T21:51:11.959028Z","steps":["trace[97048928] 'read index received' (duration: 65.797222ms)","trace[97048928] 'applied index is now lower than readState.Index' (duration: 46.926032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:51:11.959239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.892318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:51:11.959298Z","caller":"traceutil/trace.go:171","msg":"trace[1251821703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"113.00528ms","start":"2026-02-25T21:51:11.846278Z","end":"2026-02-25T21:51:11.959284Z","steps":["trace[1251821703] 'agreement among raft nodes before linearized reading' (duration: 112.877077ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:11.96098Z","caller":"traceutil/trace.go:171","msg":"trace[1546914641] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"117.315111ms","start":"2026-02-25T21:51:11.843642Z","end":"2026-02-25T21:51:11.960957Z","steps":["trace[1546914641] 'process raft request' (duration: 115.092129ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:15.330597Z","caller":"traceutil/trace.go:171","msg":"trace[871575489] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"167.491598ms","start":"2026-02-25T21:51:15.163069Z","end":"2026-02-25T21:51:15.33056Z","steps":["trace[871575489] 'process raft request' (duration: 167.273813ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:19.540252Z","caller":"traceutil/trace.go:171","msg":"trace[302253281] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"194.339134ms","start":"2026-02-25T21:51:19.345869Z","end":"2026-02-25T21:51:19.540208Z","steps":["trace[302253281] 'process raft request' (duration: 194.158019ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:25.133207Z","caller":"traceutil/trace.go:171","msg":"trace[1541082452] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"988.960771ms","start":"2026-02-25T21:51:24.14421Z","end":"2026-02-25T21:51:25.133171Z","steps":["trace[1541082452] 'process raft request' (duration: 988.655194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:25.133444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:24.144195Z","time spent":"989.112624ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:25.761449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991640386,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:51:25.995313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.798856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T21:51:25.995532Z","caller":"traceutil/trace.go:171","msg":"trace[2115252937] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:986; }","duration":"734.845136ms","start":"2026-02-25T21:51:25.260671Z","end":"2026-02-25T21:51:25.995516Z","steps":["trace[2115252937] 'read index received' (duration: 505.699293ms)","trace[2115252937] 'applied index is now lower than readState.Index' (duration: 229.144683ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:25.99569Z","caller":"traceutil/trace.go:171","msg":"trace[1649988425] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"1.847987699s","start":"2026-02-25T21:51:24.147666Z","end":"2026-02-25T21:51:25.995654Z","steps":["trace[1649988425] 'process raft request' (duration: 1.847810534s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:25.9958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.690511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T21:51:25.995838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:24.147648Z","time spent":"1.848112272s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:51:25.995846Z","caller":"traceutil/trace.go:171","msg":"trace[1412669110] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.849241818s","start":"2026-02-25T21:51:24.146591Z","end":"2026-02-25T21:51:25.995833Z","steps":["trace[1412669110] 'process raft request' (duration: 1.619797957s)","trace[1412669110] 'compare' (duration: 228.594071ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:25.995863Z","caller":"traceutil/trace.go:171","msg":"trace[1472238707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"413.798974ms","start":"2026-02-25T21:51:25.582049Z","end":"2026-02-25T21:51:25.995848Z","steps":["trace[1472238707] 'agreement among raft nodes before linearized reading' (duration: 413.61679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:25.995898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:25.582025Z","time spent":"413.867025ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T21:51:25.99591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:24.146571Z","time spent":"1.8493054s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:25.9959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.203645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T21:51:25.99596Z","caller":"traceutil/trace.go:171","msg":"trace[1736329243] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"1.849168986s","start":"2026-02-25T21:51:24.146765Z","end":"2026-02-25T21:51:25.995934Z","steps":["trace[1736329243] 'process raft request' (duration: 1.848626052s)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:25.995986Z","caller":"traceutil/trace.go:171","msg":"trace[1288701968] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:946; }","duration":"735.336498ms","start":"2026-02-25T21:51:25.260627Z","end":"2026-02-25T21:51:25.995964Z","steps":["trace[1288701968] 'agreement among raft nodes before linearized reading' (duration: 735.085473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:25.996026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.172062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:51:25.996064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:25.260603Z","time spent":"735.44551ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-02-25T21:51:25.996106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:24.146756Z","time spent":"1.849275048s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:51:25.996084Z","caller":"traceutil/trace.go:171","msg":"trace[678035795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"234.210363ms","start":"2026-02-25T21:51:25.761846Z","end":"2026-02-25T21:51:25.996056Z","steps":["trace[678035795] 'agreement among raft nodes before linearized reading' (duration: 234.136901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:26.213074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.495119ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-25T21:51:26.21331Z","caller":"traceutil/trace.go:171","msg":"trace[744179971] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"213.445409ms","start":"2026-02-25T21:51:25.999846Z","end":"2026-02-25T21:51:26.213291Z","steps":["trace[744179971] 'process raft request' (duration: 213.330256ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:26.569866Z","caller":"traceutil/trace.go:171","msg":"trace[1291951662] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"263.032322ms","start":"2026-02-25T21:51:26.306795Z","end":"2026-02-25T21:51:26.569827Z","steps":["trace[1291951662] 'read index received' (duration: 232.489862ms)","trace[1291951662] 'applied index is now lower than readState.Index' (duration: 30.54095ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:26.57043Z","caller":"traceutil/trace.go:171","msg":"trace[1683338812] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"354.704287ms","start":"2026-02-25T21:51:26.21565Z","end":"2026-02-25T21:51:26.570354Z","steps":["trace[1683338812] 'process raft request' (duration: 323.830458ms)","trace[1683338812] 'compare' (duration: 30.044699ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:51:26.570471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.634677ms","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-25T21:51:26.570633Z","caller":"traceutil/trace.go:171","msg":"trace[883664085] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:948; }","duration":"263.811882ms","start":"2026-02-25T21:51:26.306786Z","end":"2026-02-25T21:51:26.570598Z","steps":["trace[883664085] 'agreement among raft nodes before linearized reading' (duration: 263.184077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:26.571104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:26.215611Z","time spent":"355.130177ms","remote":"127.0.0.1:35360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:51:29.323794Z","caller":"traceutil/trace.go:171","msg":"trace[220249789] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"176.334966ms","start":"2026-02-25T21:51:29.147432Z","end":"2026-02-25T21:51:29.323767Z","steps":["trace[220249789] 'process raft request' (duration: 176.14109ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:32.555463Z","caller":"traceutil/trace.go:171","msg":"trace[622736486] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"477.552055ms","start":"2026-02-25T21:51:32.077879Z","end":"2026-02-25T21:51:32.555431Z","steps":["trace[622736486] 'read index received' (duration: 477.412141ms)","trace[622736486] 'applied index is now lower than readState.Index' (duration: 138.694µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:32.555518Z","caller":"traceutil/trace.go:171","msg":"trace[1038314874] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"479.605658ms","start":"2026-02-25T21:51:32.075895Z","end":"2026-02-25T21:51:32.555501Z","steps":["trace[1038314874] 'process raft request' (duration: 479.30447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:32.555712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:32.07587Z","time spent":"479.777721ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:32.555722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.835002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T21:51:32.555768Z","caller":"traceutil/trace.go:171","msg":"trace[792344504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"477.910384ms","start":"2026-02-25T21:51:32.077844Z","end":"2026-02-25T21:51:32.555754Z","steps":["trace[792344504] 'agreement among raft nodes before linearized reading' (duration: 477.75712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:32.555823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:32.077832Z","time spent":"477.978255ms","remote":"127.0.0.1:35590","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-25T21:51:33.663606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.635685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T21:51:33.663803Z","caller":"traceutil/trace.go:171","msg":"trace[416723344] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"1.108211475s","start":"2026-02-25T21:51:32.555575Z","end":"2026-02-25T21:51:33.663786Z","steps":["trace[416723344] 'read index received' (duration: 255.295973ms)","trace[416723344] 'applied index is now lower than readState.Index' (duration: 852.914702ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:33.663906Z","caller":"traceutil/trace.go:171","msg":"trace[2108777991] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.481260283s","start":"2026-02-25T21:51:32.182631Z","end":"2026-02-25T21:51:33.663892Z","steps":["trace[2108777991] 'process raft request' (duration: 628.213497ms)","trace[2108777991] 'compare' (duration: 851.747752ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:51:33.66398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:32.182604Z","time spent":"1.481337175s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:51:33.663992Z","caller":"traceutil/trace.go:171","msg":"trace[745555951] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"1.103443434s","start":"2026-02-25T21:51:32.560526Z","end":"2026-02-25T21:51:33.663969Z","steps":["trace[745555951] 'process raft request' (duration: 1.103186957s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:33.664123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:32.560501Z","time spent":"1.103547277s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:33.664293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.842719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T21:51:33.664333Z","caller":"traceutil/trace.go:171","msg":"trace[274980219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"316.915ms","start":"2026-02-25T21:51:33.347401Z","end":"2026-02-25T21:51:33.664316Z","steps":["trace[274980219] 'agreement among raft nodes before linearized reading' (duration: 316.761896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:33.664372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:33.347366Z","time spent":"316.996812ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T21:51:33.664837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.288029929s","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-25T21:51:33.664887Z","caller":"traceutil/trace.go:171","msg":"trace[454906597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:971; }","duration":"1.288119001s","start":"2026-02-25T21:51:32.376753Z","end":"2026-02-25T21:51:33.664872Z","steps":["trace[454906597] 'agreement among raft nodes before linearized reading' (duration: 1.287975168s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:33.664923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:32.37673Z","time spent":"1.288182232s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T21:51:33.66512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574247967s","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-25T21:51:33.665291Z","caller":"traceutil/trace.go:171","msg":"trace[1340332342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:971; }","duration":"1.574514244s","start":"2026-02-25T21:51:32.090742Z","end":"2026-02-25T21:51:33.665257Z","steps":["trace[1340332342] 'agreement among raft nodes before linearized reading' (duration: 1.574017921s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:33.66539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:32.09072Z","time spent":"1.574658208s","remote":"127.0.0.1:35590","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-25T21:51:33.955147Z","caller":"traceutil/trace.go:171","msg":"trace[1240845012] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"287.100522ms","start":"2026-02-25T21:51:33.668015Z","end":"2026-02-25T21:51:33.955115Z","steps":["trace[1240845012] 'process raft request' (duration: 208.109614ms)","trace[1240845012] 'compare' (duration: 78.723862ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:34.013067Z","caller":"traceutil/trace.go:171","msg":"trace[1585483588] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1016; }","duration":"250.832109ms","start":"2026-02-25T21:51:33.762204Z","end":"2026-02-25T21:51:34.013036Z","steps":["trace[1585483588] 'read index received' (duration: 113.838126ms)","trace[1585483588] 'applied index is now lower than readState.Index' (duration: 136.992223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:51:34.013209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.986243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:51:34.013256Z","caller":"traceutil/trace.go:171","msg":"trace[1848526418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"251.098707ms","start":"2026-02-25T21:51:33.762141Z","end":"2026-02-25T21:51:34.013239Z","steps":["trace[1848526418] 'agreement among raft nodes before linearized reading' (duration: 251.008984ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:34.013265Z","caller":"traceutil/trace.go:171","msg":"trace[398883682] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"343.458396ms","start":"2026-02-25T21:51:33.669785Z","end":"2026-02-25T21:51:34.013244Z","steps":["trace[398883682] 'process raft request' (duration: 342.991443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:34.013423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:33.669726Z","time spent":"343.593559ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:51:34.01361Z","caller":"traceutil/trace.go:171","msg":"trace[2068510932] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"343.571259ms","start":"2026-02-25T21:51:33.67002Z","end":"2026-02-25T21:51:34.013591Z","steps":["trace[2068510932] 'process raft request' (duration: 342.938242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:34.013738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:33.670011Z","time spent":"343.657801ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:34.637108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.441871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T21:51:34.637272Z","caller":"traceutil/trace.go:171","msg":"trace[1208565461] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:974; }","duration":"197.672407ms","start":"2026-02-25T21:51:34.439553Z","end":"2026-02-25T21:51:34.637225Z","steps":["trace[1208565461] 'count revisions from in-memory index tree' (duration: 197.273116ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:34.858534Z","caller":"traceutil/trace.go:171","msg":"trace[1379883725] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"217.271455ms","start":"2026-02-25T21:51:34.641226Z","end":"2026-02-25T21:51:34.858498Z","steps":["trace[1379883725] 'process raft request' (duration: 217.035229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:35.465466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.453781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:51:35.465482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.414389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T21:51:35.465545Z","caller":"traceutil/trace.go:171","msg":"trace[195752215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"392.579594ms","start":"2026-02-25T21:51:35.072947Z","end":"2026-02-25T21:51:35.465527Z","steps":["trace[195752215] 'range keys from in-memory index tree' (duration: 392.371219ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:35.465569Z","caller":"traceutil/trace.go:171","msg":"trace[1013896182] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:975; }","duration":"204.544731ms","start":"2026-02-25T21:51:35.261Z","end":"2026-02-25T21:51:35.465545Z","steps":["trace[1013896182] 'range keys from in-memory index tree' (duration: 204.197393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:35.465587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:35.072926Z","time spent":"392.652796ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T21:51:38.333003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530664991640483,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T21:51:38.357295Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.491172595s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T21:51:38.357756Z","caller":"traceutil/trace.go:171","msg":"trace[1011451647] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.491727029s","start":"2026-02-25T21:51:36.866006Z","end":"2026-02-25T21:51:38.357733Z","steps":["trace[1011451647] 'process raft request' (duration: 1.491584225s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:38.357892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:36.865989Z","time spent":"1.491829851s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:51:38.425556Z","caller":"traceutil/trace.go:171","msg":"trace[878183461] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1026; }","duration":"593.041814ms","start":"2026-02-25T21:51:37.832488Z","end":"2026-02-25T21:51:38.42553Z","steps":["trace[878183461] 'read index received' (duration: 525.130346ms)","trace[878183461] 'applied index is now lower than readState.Index' (duration: 67.910038ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:38.425636Z","caller":"traceutil/trace.go:171","msg":"trace[1355933314] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.188174959s","start":"2026-02-25T21:51:37.237415Z","end":"2026-02-25T21:51:38.42559Z","steps":["trace[1355933314] 'process raft request' (duration: 1.187958454s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:38.425808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.29776ms","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-25T21:51:38.425849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:37.237284Z","time spent":"1.188466517s","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:51:38.425879Z","caller":"traceutil/trace.go:171","msg":"trace[2118463705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:982; }","duration":"593.430393ms","start":"2026-02-25T21:51:37.832434Z","end":"2026-02-25T21:51:38.425865Z","steps":["trace[2118463705] 'agreement among raft nodes before linearized reading' (duration: 593.232308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:38.42592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.12877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T21:51:38.425937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:37.832409Z","time spent":"593.504286ms","remote":"127.0.0.1:35590","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-25T21:51:38.42598Z","caller":"traceutil/trace.go:171","msg":"trace[2140340488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"441.230342ms","start":"2026-02-25T21:51:37.984737Z","end":"2026-02-25T21:51:38.425967Z","steps":["trace[2140340488] 'agreement among raft nodes before linearized reading' (duration: 441.031197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:38.425976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.683247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T21:51:38.426022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:37.984716Z","time spent":"441.298404ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T21:51:38.426051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.805429ms","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-25T21:51:38.426055Z","caller":"traceutil/trace.go:171","msg":"trace[503070007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:982; }","duration":"368.7909ms","start":"2026-02-25T21:51:38.057243Z","end":"2026-02-25T21:51:38.426033Z","steps":["trace[503070007] 'agreement among raft nodes before linearized reading' (duration: 368.556494ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:38.42609Z","caller":"traceutil/trace.go:171","msg":"trace[1995508219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:982; }","duration":"377.880531ms","start":"2026-02-25T21:51:38.048199Z","end":"2026-02-25T21:51:38.42608Z","steps":["trace[1995508219] 'agreement among raft nodes before linearized reading' (duration: 377.777648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:38.426101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:38.057229Z","time spent":"368.863312ms","remote":"127.0.0.1:35590","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-25T21:51:38.42612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:38.048177Z","time spent":"377.937073ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T21:51:38.656308Z","caller":"traceutil/trace.go:171","msg":"trace[639120141] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"225.152786ms","start":"2026-02-25T21:51:38.431128Z","end":"2026-02-25T21:51:38.656281Z","steps":["trace[639120141] 'process raft request' (duration: 155.425513ms)","trace[639120141] 'compare' (duration: 69.374184ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:38.736969Z","caller":"traceutil/trace.go:171","msg":"trace[1394268106] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"305.623783ms","start":"2026-02-25T21:51:38.431322Z","end":"2026-02-25T21:51:38.736945Z","steps":["trace[1394268106] 'process raft request' (duration: 305.581372ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:38.737052Z","caller":"traceutil/trace.go:171","msg":"trace[1872894409] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"305.715625ms","start":"2026-02-25T21:51:38.431267Z","end":"2026-02-25T21:51:38.736982Z","steps":["trace[1872894409] 'process raft request' (duration: 305.479179ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:38.737058Z","caller":"traceutil/trace.go:171","msg":"trace[942000542] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"305.746276ms","start":"2026-02-25T21:51:38.431279Z","end":"2026-02-25T21:51:38.737026Z","steps":["trace[942000542] 'process raft request' (duration: 305.589602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:38.737214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:38.431245Z","time spent":"305.88681ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:38.73723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:38.431263Z","time spent":"305.880359ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:38.737123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:38.431297Z","time spent":"305.746575ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:41.287682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.41207ms","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-25T21:51:41.287754Z","caller":"traceutil/trace.go:171","msg":"trace[1627562684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:988; }","duration":"545.532843ms","start":"2026-02-25T21:51:40.742206Z","end":"2026-02-25T21:51:41.287739Z","steps":["trace[1627562684] 'range keys from in-memory index tree' (duration: 545.207765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:41.287794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:40.742182Z","time spent":"545.603926ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T21:51:41.287895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.565685ms","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-25T21:51:41.287955Z","caller":"traceutil/trace.go:171","msg":"trace[430180713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:988; }","duration":"545.668498ms","start":"2026-02-25T21:51:40.742274Z","end":"2026-02-25T21:51:41.287943Z","steps":["trace[430180713] 'range keys from in-memory index tree' (duration: 545.445552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:41.287996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:40.742244Z","time spent":"545.740939ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T21:51:41.287993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.13009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T21:51:41.287999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.748305ms","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-25T21:51:41.288078Z","caller":"traceutil/trace.go:171","msg":"trace[443631577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"526.250823ms","start":"2026-02-25T21:51:40.761807Z","end":"2026-02-25T21:51:41.288058Z","steps":["trace[443631577] 'range keys from in-memory index tree' (duration: 526.061629ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:41.288084Z","caller":"traceutil/trace.go:171","msg":"trace[1805372660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:988; }","duration":"546.871439ms","start":"2026-02-25T21:51:40.741194Z","end":"2026-02-25T21:51:41.288066Z","steps":["trace[1805372660] 'range keys from in-memory index tree' (duration: 546.605402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:41.28813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:40.741178Z","time spent":"546.94254ms","remote":"127.0.0.1:35590","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-25T21:51:41.288137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:40.761795Z","time spent":"526.323245ms","remote":"127.0.0.1:35314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T21:51:41.288411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.943951ms","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-25T21:51:41.288458Z","caller":"traceutil/trace.go:171","msg":"trace[630623675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"628.029183ms","start":"2026-02-25T21:51:40.660418Z","end":"2026-02-25T21:51:41.288447Z","steps":["trace[630623675] 'range keys from in-memory index tree' (duration: 627.163121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:41.288485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:40.660396Z","time spent":"628.081755ms","remote":"127.0.0.1:35590","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-25T21:51:41.673758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.86076ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T21:51:41.689328Z","caller":"traceutil/trace.go:171","msg":"trace[1532158304] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"407.926625ms","start":"2026-02-25T21:51:41.28137Z","end":"2026-02-25T21:51:41.689297Z","steps":["trace[1532158304] 'process raft request' (duration: 407.685349ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:41.689385Z","caller":"traceutil/trace.go:171","msg":"trace[1722866150] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"396.080443ms","start":"2026-02-25T21:51:41.293276Z","end":"2026-02-25T21:51:41.689356Z","steps":["trace[1722866150] 'process raft request' (duration: 395.971241ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:41.689417Z","caller":"traceutil/trace.go:171","msg":"trace[212503166] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"396.289999ms","start":"2026-02-25T21:51:41.293096Z","end":"2026-02-25T21:51:41.689386Z","steps":["trace[212503166] 'process raft request' (duration: 396.086634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:41.689501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:41.281318Z","time spent":"408.11396ms","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T21:51:41.689516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:41.293254Z","time spent":"396.188997ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:41.689588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:41.293072Z","time spent":"396.415683ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T21:51:41.6897Z","caller":"traceutil/trace.go:171","msg":"trace[2064698289] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"396.31409ms","start":"2026-02-25T21:51:41.293366Z","end":"2026-02-25T21:51:41.68968Z","steps":["trace[2064698289] 'process raft request' (duration: 395.9349ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:41.689723Z","caller":"traceutil/trace.go:171","msg":"trace[472645704] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"396.303969ms","start":"2026-02-25T21:51:41.293398Z","end":"2026-02-25T21:51:41.689702Z","steps":["trace[472645704] 'process raft request' (duration: 396.181616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:41.689722Z","caller":"traceutil/trace.go:171","msg":"trace[2021126155] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1034; }","duration":"400.140027ms","start":"2026-02-25T21:51:41.289558Z","end":"2026-02-25T21:51:41.689698Z","steps":["trace[2021126155] 'read index received' (duration: 30.467705ms)","trace[2021126155] 'applied index is now lower than readState.Index' (duration: 369.671472ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:51:41.689794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:41.293316Z","time spent":"396.434312ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:41.689804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:41.29339Z","time spent":"396.376162ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:51:41.689834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.2637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:51:41.689868Z","caller":"traceutil/trace.go:171","msg":"trace[1521977200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"400.360543ms","start":"2026-02-25T21:51:41.289497Z","end":"2026-02-25T21:51:41.689858Z","steps":["trace[1521977200] 'agreement among raft nodes before linearized reading' (duration: 400.289051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:41.689913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:41.289474Z","time spent":"400.423484ms","remote":"127.0.0.1:35330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T21:51:43.286326Z","caller":"traceutil/trace.go:171","msg":"trace[100088167] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"303.015136ms","start":"2026-02-25T21:51:42.983273Z","end":"2026-02-25T21:51:43.286288Z","steps":["trace[100088167] 'process raft request' (duration: 301.535208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:43.286548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:42.983259Z","time spent":"303.199212ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T21:51:44.183043Z","caller":"traceutil/trace.go:171","msg":"trace[969048131] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"115.759915ms","start":"2026-02-25T21:51:44.067258Z","end":"2026-02-25T21:51:44.183018Z","steps":["trace[969048131] 'process raft request' (duration: 115.614991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:47.132385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.455987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T21:51:47.132515Z","caller":"traceutil/trace.go:171","msg":"trace[9618825] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"336.493077ms","start":"2026-02-25T21:51:46.796Z","end":"2026-02-25T21:51:47.132493Z","steps":["trace[9618825] 'process raft request' (duration: 150.838165ms)","trace[9618825] 'compare' (duration: 185.292743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:51:47.1326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:46.795967Z","time spent":"336.60656ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T21:51:50.910419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.999851ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T21:51:50.910553Z","caller":"traceutil/trace.go:171","msg":"trace[829950672] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"146.550957ms","start":"2026-02-25T21:51:50.763978Z","end":"2026-02-25T21:51:50.910529Z","steps":["trace[829950672] 'read index received' (duration: 28.460054ms)","trace[829950672] 'applied index is now lower than readState.Index' (duration: 118.088683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:51:50.91061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.625439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:51:50.910653Z","caller":"traceutil/trace.go:171","msg":"trace[1884319514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1069; }","duration":"146.681351ms","start":"2026-02-25T21:51:50.76396Z","end":"2026-02-25T21:51:50.910641Z","steps":["trace[1884319514] 'agreement among raft nodes before linearized reading' (duration: 146.594998ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:51.274574Z","caller":"traceutil/trace.go:171","msg":"trace[783582984] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"171.234335ms","start":"2026-02-25T21:51:51.103309Z","end":"2026-02-25T21:51:51.274543Z","steps":["trace[783582984] 'process raft request' (duration: 171.03177ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:55.911792Z","caller":"traceutil/trace.go:171","msg":"trace[110491263] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1138; }","duration":"139.526378ms","start":"2026-02-25T21:51:55.772239Z","end":"2026-02-25T21:51:55.911765Z","steps":["trace[110491263] 'read index received' (duration: 64.453829ms)","trace[110491263] 'applied index is now lower than readState.Index' (duration: 75.071579ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:55.911916Z","caller":"traceutil/trace.go:171","msg":"trace[1365379764] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"135.902136ms","start":"2026-02-25T21:51:55.77599Z","end":"2026-02-25T21:51:55.911893Z","steps":["trace[1365379764] 'process raft request' (duration: 135.730902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:55.912088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.765365ms","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-25T21:51:55.911841Z","caller":"traceutil/trace.go:171","msg":"trace[1737140521] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"139.58498ms","start":"2026-02-25T21:51:55.772213Z","end":"2026-02-25T21:51:55.911798Z","steps":["trace[1737140521] 'process raft request' (duration: 92.733698ms)","trace[1737140521] 'compare' (duration: 46.655997ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:51:55.912371Z","caller":"traceutil/trace.go:171","msg":"trace[1507426676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"139.904598ms","start":"2026-02-25T21:51:55.772223Z","end":"2026-02-25T21:51:55.912127Z","steps":["trace[1507426676] 'agreement among raft nodes before linearized reading' (duration: 139.651362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:51:56.88293Z","caller":"traceutil/trace.go:171","msg":"trace[782002200] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"303.521739ms","start":"2026-02-25T21:51:56.57938Z","end":"2026-02-25T21:51:56.882902Z","steps":["trace[782002200] 'process raft request' (duration: 303.330835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:51:56.883127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:51:56.579367Z","time spent":"303.659923ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:52:26.38363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.805899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T21:52:26.383783Z","caller":"traceutil/trace.go:171","msg":"trace[1155959323] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"267.187895ms","start":"2026-02-25T21:52:26.116569Z","end":"2026-02-25T21:52:26.383757Z","steps":["trace[1155959323] 'process raft request' (duration: 130.139989ms)","trace[1155959323] 'compare' (duration: 136.636765ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:52:50.876646Z","caller":"traceutil/trace.go:171","msg":"trace[1344810064] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"114.527889ms","start":"2026-02-25T21:52:50.76208Z","end":"2026-02-25T21:52:50.876608Z","steps":["trace[1344810064] 'read index received' (duration: 69.040915ms)","trace[1344810064] 'applied index is now lower than readState.Index' (duration: 45.485203ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T21:52:50.876877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.757354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T21:52:50.877089Z","caller":"traceutil/trace.go:171","msg":"trace[1256306335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"115.042311ms","start":"2026-02-25T21:52:50.762021Z","end":"2026-02-25T21:52:50.877063Z","steps":["trace[1256306335] 'agreement among raft nodes before linearized reading' (duration: 114.732954ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:52:59.357583Z","caller":"traceutil/trace.go:171","msg":"trace[1701701970] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"187.975156ms","start":"2026-02-25T21:52:59.169564Z","end":"2026-02-25T21:52:59.357539Z","steps":["trace[1701701970] 'process raft request' (duration: 187.653229ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:53:06.742755Z","caller":"traceutil/trace.go:171","msg":"trace[1265347269] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"202.843595ms","start":"2026-02-25T21:53:06.539885Z","end":"2026-02-25T21:53:06.742728Z","steps":["trace[1265347269] 'process raft request' (duration: 156.789097ms)","trace[1265347269] 'compare' (duration: 45.754231ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:53:10.535336Z","caller":"traceutil/trace.go:171","msg":"trace[2082614242] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"175.384092ms","start":"2026-02-25T21:53:10.359911Z","end":"2026-02-25T21:53:10.535295Z","steps":["trace[2082614242] 'process raft request' (duration: 100.195534ms)","trace[2082614242] 'compare' (duration: 75.013084ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:53:12.284934Z","caller":"traceutil/trace.go:171","msg":"trace[1129623965] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"456.334263ms","start":"2026-02-25T21:53:11.828569Z","end":"2026-02-25T21:53:12.284903Z","steps":["trace[1129623965] 'process raft request' (duration: 456.19044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:53:12.285101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T21:53:11.828537Z","time spent":"456.482576ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T21:53:12.710854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.657324ms","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-25T21:53:12.710935Z","caller":"traceutil/trace.go:171","msg":"trace[1469890820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1400; }","duration":"171.816798ms","start":"2026-02-25T21:53:12.5391Z","end":"2026-02-25T21:53:12.710917Z","steps":["trace[1469890820] 'range keys from in-memory index tree' (duration: 171.464591ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:53:17.603364Z","caller":"traceutil/trace.go:171","msg":"trace[965708747] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"114.377895ms","start":"2026-02-25T21:53:17.488952Z","end":"2026-02-25T21:53:17.60333Z","steps":["trace[965708747] 'process raft request' (duration: 114.177951ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:53:57.124432Z","caller":"traceutil/trace.go:171","msg":"trace[883889226] linearizableReadLoop","detail":"{readStateIndex:1656; appliedIndex:1655; }","duration":"109.651961ms","start":"2026-02-25T21:53:57.014752Z","end":"2026-02-25T21:53:57.124404Z","steps":["trace[883889226] 'read index received' (duration: 109.480076ms)","trace[883889226] 'applied index is now lower than readState.Index' (duration: 170.775µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:53:57.124504Z","caller":"traceutil/trace.go:171","msg":"trace[812876395] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"111.957813ms","start":"2026-02-25T21:53:57.0125Z","end":"2026-02-25T21:53:57.124458Z","steps":["trace[812876395] 'process raft request' (duration: 111.748987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:53:57.124764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.976678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T21:53:57.124871Z","caller":"traceutil/trace.go:171","msg":"trace[1830188714] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1582; }","duration":"110.163992ms","start":"2026-02-25T21:53:57.014688Z","end":"2026-02-25T21:53:57.124852Z","steps":["trace[1830188714] 'agreement among raft nodes before linearized reading' (duration: 109.824054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T21:54:59.676208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.430635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T21:54:59.676519Z","caller":"traceutil/trace.go:171","msg":"trace[413842154] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"172.044976ms","start":"2026-02-25T21:54:59.504402Z","end":"2026-02-25T21:54:59.676447Z","steps":["trace[413842154] 'process raft request' (duration: 171.990035ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T21:54:59.676651Z","caller":"traceutil/trace.go:171","msg":"trace[392282283] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"173.004977ms","start":"2026-02-25T21:54:59.503444Z","end":"2026-02-25T21:54:59.676449Z","steps":["trace[392282283] 'process raft request' (duration: 69.201503ms)","trace[392282283] 'compare' (duration: 103.275521ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T21:54:59.676763Z","caller":"traceutil/trace.go:171","msg":"trace[2124405794] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"172.22887ms","start":"2026-02-25T21:54:59.504237Z","end":"2026-02-25T21:54:59.676466Z","steps":["trace[2124405794] 'process raft request' (duration: 172.096587ms)"],"step_count":1}