{"level":"warn","ts":"2026-02-11T21:26:08.529705Z","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-11T21:26:08.529877Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-11T21:26:08.529968Z","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-11T21:26:08.529999Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-11T21:26:08.530052Z","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-11T21:26:08.530813Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-02-11T21:26:08.530953Z","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":2,"max-cpu-available":2,"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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-11T21:26:08.53962Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.409653ms"} {"level":"info","ts":"2026-02-11T21:26:08.57641Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-02-11T21:26:08.576469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T21:26:08.576709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-02-11T21:26:08.576786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T21:26:08.576803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-02-11T21:26:08.57697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-02-11T21:26:08.591417Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T21:26:08.593004Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T21:26:08.594589Z","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-11T21:26:08.597575Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T21:26:08.602518Z","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-11T21:26:08.603024Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T21:26:08.603273Z","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-11T21:26:08.603319Z","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-11T21:26:08.603473Z","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-11T21:26:08.603852Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-11T21:26:08.603873Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-11T21:26:08.60569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-02-11T21:26:08.60588Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-11T21:26:08.608458Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T21:26:08.608492Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T21:26:08.981145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T21:26:08.981229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T21:26:08.981843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-02-11T21:26:08.981872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-02-11T21:26:08.981888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-11T21:26:08.981907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-02-11T21:26:08.981928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-11T21:26:08.987003Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T21:26:08.987055Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T21:26:08.987301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T21:26:08.987299Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T21:26:08.9886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T21:26:08.988727Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T21:26:08.988748Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T21:26:08.988882Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T21:26:08.989026Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T21:26:08.989052Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T21:26:08.994024Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-02-11T21:26:33.00343Z","caller":"traceutil/trace.go:171","msg":"trace[449281912] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"291.921927ms","start":"2026-02-11T21:26:32.711489Z","end":"2026-02-11T21:26:33.00341Z","steps":["trace[449281912] 'read index received' (duration: 291.769824ms)","trace[449281912] 'applied index is now lower than readState.Index' (duration: 150.993µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:26:33.003644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:32.314692Z","time spent":"688.949577ms","remote":"127.0.0.1:51120","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T21:26:33.003849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.688353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:26:33.003972Z","caller":"traceutil/trace.go:171","msg":"trace[1256535720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"196.865375ms","start":"2026-02-11T21:26:32.807081Z","end":"2026-02-11T21:26:33.003946Z","steps":["trace[1256535720] 'agreement among raft nodes before linearized reading' (duration: 196.661112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:33.004334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.831153ms","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-11T21:26:33.004377Z","caller":"traceutil/trace.go:171","msg":"trace[1704284812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:423; }","duration":"292.922524ms","start":"2026-02-11T21:26:32.711441Z","end":"2026-02-11T21:26:33.004363Z","steps":["trace[1704284812] 'agreement among raft nodes before linearized reading' (duration: 292.843353ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:26:33.248344Z","caller":"traceutil/trace.go:171","msg":"trace[1102756901] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"241.56741ms","start":"2026-02-11T21:26:33.006763Z","end":"2026-02-11T21:26:33.24833Z","steps":["trace[1102756901] 'process raft request' (duration: 235.190753ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:26:33.248296Z","caller":"traceutil/trace.go:171","msg":"trace[2035354466] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"241.117722ms","start":"2026-02-11T21:26:33.007155Z","end":"2026-02-11T21:26:33.248273Z","steps":["trace[2035354466] 'read index received' (duration: 234.808747ms)","trace[2035354466] 'applied index is now lower than readState.Index' (duration: 6.307975ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:26:33.24894Z","caller":"traceutil/trace.go:171","msg":"trace[847945879] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"239.040383ms","start":"2026-02-11T21:26:33.009876Z","end":"2026-02-11T21:26:33.248916Z","steps":["trace[847945879] 'process raft request' (duration: 239.000012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:33.248964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.788714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:26:33.249Z","caller":"traceutil/trace.go:171","msg":"trace[139735110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"241.858834ms","start":"2026-02-11T21:26:33.007129Z","end":"2026-02-11T21:26:33.248988Z","steps":["trace[139735110] 'agreement among raft nodes before linearized reading' (duration: 241.752653ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:26:33.24913Z","caller":"traceutil/trace.go:171","msg":"trace[2126606766] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"240.574771ms","start":"2026-02-11T21:26:33.008549Z","end":"2026-02-11T21:26:33.249124Z","steps":["trace[2126606766] 'process raft request' (duration: 240.219345ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:26:33.249311Z","caller":"traceutil/trace.go:171","msg":"trace[542788823] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"238.609055ms","start":"2026-02-11T21:26:33.010697Z","end":"2026-02-11T21:26:33.249306Z","steps":["trace[542788823] 'process raft request' (duration: 238.198538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:36.176917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438029178356984,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T21:26:36.226247Z","caller":"traceutil/trace.go:171","msg":"trace[1463394583] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:474; }","duration":"550.029835ms","start":"2026-02-11T21:26:35.676197Z","end":"2026-02-11T21:26:36.226227Z","steps":["trace[1463394583] 'read index received' (duration: 534.798598ms)","trace[1463394583] 'applied index is now lower than readState.Index' (duration: 15.230357ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:26:36.226564Z","caller":"traceutil/trace.go:171","msg":"trace[1529657969] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"678.402104ms","start":"2026-02-11T21:26:35.548148Z","end":"2026-02-11T21:26:36.22655Z","steps":["trace[1529657969] 'process raft request' (duration: 662.835631ms)","trace[1529657969] 'compare' (duration: 12.781063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:26:36.227281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:35.548136Z","time spent":"678.636498ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:26:36.227654Z","caller":"traceutil/trace.go:171","msg":"trace[147291915] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"665.592261ms","start":"2026-02-11T21:26:35.56205Z","end":"2026-02-11T21:26:36.227642Z","steps":["trace[147291915] 'process raft request' (duration: 663.248009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:36.227853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:35.562032Z","time spent":"665.756694ms","remote":"127.0.0.1:51272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16641,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:26:36.229001Z","caller":"traceutil/trace.go:171","msg":"trace[1039388213] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"662.115286ms","start":"2026-02-11T21:26:35.566872Z","end":"2026-02-11T21:26:36.228988Z","steps":["trace[1039388213] 'process raft request' (duration: 658.531921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:36.231293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:35.566863Z","time spent":"662.174327ms","remote":"127.0.0.1:51180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317259,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:26:36.231382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.18387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-11T21:26:36.231403Z","caller":"traceutil/trace.go:171","msg":"trace[1261192149] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:463; }","duration":"555.23207ms","start":"2026-02-11T21:26:35.676163Z","end":"2026-02-11T21:26:36.231395Z","steps":["trace[1261192149] 'agreement among raft nodes before linearized reading' (duration: 555.19152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:36.231422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:35.676149Z","time spent":"555.269001ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-11T21:26:36.231526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.660451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-11T21:26:36.231543Z","caller":"traceutil/trace.go:171","msg":"trace[1595736802] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:463; }","duration":"554.713461ms","start":"2026-02-11T21:26:35.676824Z","end":"2026-02-11T21:26:36.231537Z","steps":["trace[1595736802] 'agreement among raft nodes before linearized reading' (duration: 554.68399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:36.231558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:35.676802Z","time spent":"554.752352ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-02-11T21:26:38.537745Z","caller":"traceutil/trace.go:171","msg":"trace[1918042951] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"483.22755ms","start":"2026-02-11T21:26:38.054491Z","end":"2026-02-11T21:26:38.537718Z","steps":["trace[1918042951] 'process raft request' (duration: 483.086378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:38.537899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:38.054481Z","time spent":"483.350002ms","remote":"127.0.0.1:51268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:26:39.464491Z","caller":"traceutil/trace.go:171","msg":"trace[860825688] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"187.058275ms","start":"2026-02-11T21:26:39.277411Z","end":"2026-02-11T21:26:39.464469Z","steps":["trace[860825688] 'read index received' (duration: 186.844922ms)","trace[860825688] 'applied index is now lower than readState.Index' (duration: 212.393µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:26:39.464625Z","caller":"traceutil/trace.go:171","msg":"trace[1597711796] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"191.078629ms","start":"2026-02-11T21:26:39.273535Z","end":"2026-02-11T21:26:39.464614Z","steps":["trace[1597711796] 'process raft request' (duration: 190.769443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:39.46478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.132017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T21:26:39.464845Z","caller":"traceutil/trace.go:171","msg":"trace[2132932446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:473; }","duration":"187.196498ms","start":"2026-02-11T21:26:39.27762Z","end":"2026-02-11T21:26:39.464817Z","steps":["trace[2132932446] 'agreement among raft nodes before linearized reading' (duration: 187.066456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:39.465025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.24495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T21:26:39.465047Z","caller":"traceutil/trace.go:171","msg":"trace[874359243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"176.28216ms","start":"2026-02-11T21:26:39.288758Z","end":"2026-02-11T21:26:39.46504Z","steps":["trace[874359243] 'agreement among raft nodes before linearized reading' (duration: 176.215269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:39.465043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.611626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-11T21:26:39.465156Z","caller":"traceutil/trace.go:171","msg":"trace[1277726210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"187.742179ms","start":"2026-02-11T21:26:39.277401Z","end":"2026-02-11T21:26:39.465143Z","steps":["trace[1277726210] 'agreement among raft nodes before linearized reading' (duration: 187.528415ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:26:41.663797Z","caller":"traceutil/trace.go:171","msg":"trace[1159007750] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"189.757536ms","start":"2026-02-11T21:26:41.474018Z","end":"2026-02-11T21:26:41.663775Z","steps":["trace[1159007750] 'read index received' (duration: 189.594943ms)","trace[1159007750] 'applied index is now lower than readState.Index' (duration: 159.823µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:26:41.664762Z","caller":"traceutil/trace.go:171","msg":"trace[933129813] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"193.411362ms","start":"2026-02-11T21:26:41.471333Z","end":"2026-02-11T21:26:41.664745Z","steps":["trace[933129813] 'process raft request' (duration: 192.331972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:41.665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.962368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T21:26:41.665074Z","caller":"traceutil/trace.go:171","msg":"trace[949120015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"191.05514ms","start":"2026-02-11T21:26:41.474005Z","end":"2026-02-11T21:26:41.66506Z","steps":["trace[949120015] 'agreement among raft nodes before linearized reading' (duration: 190.943848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:41.665334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.716018ms","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-11T21:26:41.665446Z","caller":"traceutil/trace.go:171","msg":"trace[1710288966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:478; }","duration":"187.840411ms","start":"2026-02-11T21:26:41.477594Z","end":"2026-02-11T21:26:41.665434Z","steps":["trace[1710288966] 'agreement among raft nodes before linearized reading' (duration: 187.706098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:41.665778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.801361ms","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-11T21:26:41.665818Z","caller":"traceutil/trace.go:171","msg":"trace[1198856446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"186.883862ms","start":"2026-02-11T21:26:41.478925Z","end":"2026-02-11T21:26:41.665809Z","steps":["trace[1198856446] 'agreement among raft nodes before linearized reading' (duration: 186.77471ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:26:45.906407Z","caller":"traceutil/trace.go:171","msg":"trace[2058243282] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"222.861738ms","start":"2026-02-11T21:26:45.683525Z","end":"2026-02-11T21:26:45.906386Z","steps":["trace[2058243282] 'read index received' (duration: 222.677405ms)","trace[2058243282] 'applied index is now lower than readState.Index' (duration: 183.413µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:26:45.906675Z","caller":"traceutil/trace.go:171","msg":"trace[698979072] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"228.502991ms","start":"2026-02-11T21:26:45.678162Z","end":"2026-02-11T21:26:45.906665Z","steps":["trace[698979072] 'process raft request' (duration: 228.115264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:45.906862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.323607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T21:26:45.906893Z","caller":"traceutil/trace.go:171","msg":"trace[155342578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:491; }","duration":"223.397769ms","start":"2026-02-11T21:26:45.683486Z","end":"2026-02-11T21:26:45.906884Z","steps":["trace[155342578] 'agreement among raft nodes before linearized reading' (duration: 223.332627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:45.907023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.301564ms","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-11T21:26:45.907048Z","caller":"traceutil/trace.go:171","msg":"trace[826505092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"219.329575ms","start":"2026-02-11T21:26:45.687712Z","end":"2026-02-11T21:26:45.907042Z","steps":["trace[826505092] 'agreement among raft nodes before linearized reading' (duration: 219.284694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:45.907258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.720565ms","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-11T21:26:45.907285Z","caller":"traceutil/trace.go:171","msg":"trace[915659354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"217.758716ms","start":"2026-02-11T21:26:45.689519Z","end":"2026-02-11T21:26:45.907278Z","steps":["trace[915659354] 'agreement among raft nodes before linearized reading' (duration: 217.665424ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:26:46.395377Z","caller":"traceutil/trace.go:171","msg":"trace[1466397457] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:508; }","duration":"253.457696ms","start":"2026-02-11T21:26:46.141896Z","end":"2026-02-11T21:26:46.395354Z","steps":["trace[1466397457] 'read index received' (duration: 222.567893ms)","trace[1466397457] 'applied index is now lower than readState.Index' (duration: 30.889193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:26:46.395591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.67664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d2drh\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-11T21:26:46.395623Z","caller":"traceutil/trace.go:171","msg":"trace[596179549] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d2drh; range_end:; response_count:1; response_revision:494; }","duration":"253.759212ms","start":"2026-02-11T21:26:46.141854Z","end":"2026-02-11T21:26:46.395613Z","steps":["trace[596179549] 'agreement among raft nodes before linearized reading' (duration: 253.582868ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:26:46.395869Z","caller":"traceutil/trace.go:171","msg":"trace[104586869] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"477.502486ms","start":"2026-02-11T21:26:45.918355Z","end":"2026-02-11T21:26:46.395857Z","steps":["trace[104586869] 'process raft request' (duration: 446.168584ms)","trace[104586869] 'compare' (duration: 30.326693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:26:46.395943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:45.918336Z","time spent":"477.557887ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:26:46.396065Z","caller":"traceutil/trace.go:171","msg":"trace[1696279826] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"476.491737ms","start":"2026-02-11T21:26:45.919565Z","end":"2026-02-11T21:26:46.396057Z","steps":["trace[1696279826] 'process raft request' (duration: 475.745093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:46.396111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:45.919559Z","time spent":"476.528228ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:26:48.597899Z","caller":"traceutil/trace.go:171","msg":"trace[996330765] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"192.046587ms","start":"2026-02-11T21:26:48.405563Z","end":"2026-02-11T21:26:48.597609Z","steps":["trace[996330765] 'process raft request' (duration: 149.836528ms)","trace[996330765] 'compare' (duration: 42.048556ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:26:48.669634Z","caller":"traceutil/trace.go:171","msg":"trace[1594519601] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"263.540839ms","start":"2026-02-11T21:26:48.406073Z","end":"2026-02-11T21:26:48.669614Z","steps":["trace[1594519601] 'process raft request' (duration: 263.133871ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:26:59.20104Z","caller":"traceutil/trace.go:171","msg":"trace[856351936] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"332.006516ms","start":"2026-02-11T21:26:58.869017Z","end":"2026-02-11T21:26:59.201024Z","steps":["trace[856351936] 'process raft request' (duration: 331.899884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:59.201159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:58.869Z","time spent":"332.090257ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:26:59.753284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.481161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-9z7kp\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-02-11T21:26:59.753354Z","caller":"traceutil/trace.go:171","msg":"trace[1322067873] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-9z7kp; range_end:; response_count:1; response_revision:657; }","duration":"502.593052ms","start":"2026-02-11T21:26:59.25073Z","end":"2026-02-11T21:26:59.753323Z","steps":["trace[1322067873] 'range keys from in-memory index tree' (duration: 502.327177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:26:59.753376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:26:59.250712Z","time spent":"502.658894ms","remote":"127.0.0.1:51272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5049,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-9z7kp\" "} {"level":"info","ts":"2026-02-11T21:27:00.878167Z","caller":"traceutil/trace.go:171","msg":"trace[223036773] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"145.144483ms","start":"2026-02-11T21:27:00.733008Z","end":"2026-02-11T21:27:00.878152Z","steps":["trace[223036773] 'read index received' (duration: 144.99198ms)","trace[223036773] 'applied index is now lower than readState.Index' (duration: 151.973µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:27:00.87823Z","caller":"traceutil/trace.go:171","msg":"trace[2063384901] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"626.738583ms","start":"2026-02-11T21:27:00.251486Z","end":"2026-02-11T21:27:00.878225Z","steps":["trace[2063384901] 'process raft request' (duration: 626.55673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:00.8783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:00.251476Z","time spent":"626.767263ms","remote":"127.0.0.1:51156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T21:27:00.878564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.563011ms","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-11T21:27:00.878599Z","caller":"traceutil/trace.go:171","msg":"trace[746064248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"145.636393ms","start":"2026-02-11T21:27:00.732958Z","end":"2026-02-11T21:27:00.878594Z","steps":["trace[746064248] 'agreement among raft nodes before linearized reading' (duration: 145.518451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:00.878714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.714593ms","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-11T21:27:00.878726Z","caller":"traceutil/trace.go:171","msg":"trace[571519819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"101.745193ms","start":"2026-02-11T21:27:00.776977Z","end":"2026-02-11T21:27:00.878722Z","steps":["trace[571519819] 'agreement among raft nodes before linearized reading' (duration: 101.708032ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:06.398741Z","caller":"traceutil/trace.go:171","msg":"trace[466110194] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"365.517066ms","start":"2026-02-11T21:27:06.033197Z","end":"2026-02-11T21:27:06.398714Z","steps":["trace[466110194] 'read index received' (duration: 365.334613ms)","trace[466110194] 'applied index is now lower than readState.Index' (duration: 181.563µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:06.398944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.72227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-11T21:27:06.398977Z","caller":"traceutil/trace.go:171","msg":"trace[1444144105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"365.788132ms","start":"2026-02-11T21:27:06.033177Z","end":"2026-02-11T21:27:06.398965Z","steps":["trace[1444144105] 'agreement among raft nodes before linearized reading' (duration: 365.630679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:06.399007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:06.033166Z","time spent":"365.834192ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T21:27:06.399275Z","caller":"traceutil/trace.go:171","msg":"trace[460570305] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"407.187585ms","start":"2026-02-11T21:27:05.992076Z","end":"2026-02-11T21:27:06.399264Z","steps":["trace[460570305] 'process raft request' (duration: 406.506572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:06.399352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:05.992066Z","time spent":"407.234917ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:27:06.790639Z","caller":"traceutil/trace.go:171","msg":"trace[2068745058] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"385.654802ms","start":"2026-02-11T21:27:06.404962Z","end":"2026-02-11T21:27:06.790616Z","steps":["trace[2068745058] 'process raft request' (duration: 332.81575ms)","trace[2068745058] 'compare' (duration: 52.746571ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:06.790801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:06.40495Z","time spent":"385.759754ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:27:20.664582Z","caller":"traceutil/trace.go:171","msg":"trace[1798749273] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"195.836828ms","start":"2026-02-11T21:27:20.468728Z","end":"2026-02-11T21:27:20.664565Z","steps":["trace[1798749273] 'process raft request' (duration: 195.700706ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:25.351521Z","caller":"traceutil/trace.go:171","msg":"trace[2025339779] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"260.277549ms","start":"2026-02-11T21:27:25.091226Z","end":"2026-02-11T21:27:25.351503Z","steps":["trace[2025339779] 'read index received' (duration: 260.156627ms)","trace[2025339779] 'applied index is now lower than readState.Index' (duration: 119.982µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:25.351723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.474582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T21:27:25.351745Z","caller":"traceutil/trace.go:171","msg":"trace[2053199411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"260.525503ms","start":"2026-02-11T21:27:25.091213Z","end":"2026-02-11T21:27:25.351739Z","steps":["trace[2053199411] 'agreement among raft nodes before linearized reading' (duration: 260.35958ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:25.351818Z","caller":"traceutil/trace.go:171","msg":"trace[720277939] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"260.900121ms","start":"2026-02-11T21:27:25.090894Z","end":"2026-02-11T21:27:25.351794Z","steps":["trace[720277939] 'process raft request' (duration: 260.497644ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:25.702076Z","caller":"traceutil/trace.go:171","msg":"trace[232437060] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"211.606034ms","start":"2026-02-11T21:27:25.490452Z","end":"2026-02-11T21:27:25.702058Z","steps":["trace[232437060] 'process raft request' (duration: 211.570494ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:25.702853Z","caller":"traceutil/trace.go:171","msg":"trace[1397926715] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"348.000227ms","start":"2026-02-11T21:27:25.354842Z","end":"2026-02-11T21:27:25.702842Z","steps":["trace[1397926715] 'process raft request' (duration: 275.692878ms)","trace[1397926715] 'compare' (duration: 71.099236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:25.702931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:25.354827Z","time spent":"348.047627ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:27:25.703073Z","caller":"traceutil/trace.go:171","msg":"trace[934307252] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"252.916141ms","start":"2026-02-11T21:27:25.450148Z","end":"2026-02-11T21:27:25.703064Z","steps":["trace[934307252] 'read index received' (duration: 180.412019ms)","trace[934307252] 'applied index is now lower than readState.Index' (duration: 72.503032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:25.703174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.108064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T21:27:25.703194Z","caller":"traceutil/trace.go:171","msg":"trace[934641997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"253.156835ms","start":"2026-02-11T21:27:25.450031Z","end":"2026-02-11T21:27:25.703187Z","steps":["trace[934641997] 'agreement among raft nodes before linearized reading' (duration: 253.060273ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:27.585747Z","caller":"traceutil/trace.go:171","msg":"trace[1525749437] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"176.70989ms","start":"2026-02-11T21:27:27.409015Z","end":"2026-02-11T21:27:27.585725Z","steps":["trace[1525749437] 'read index received' (duration: 176.501796ms)","trace[1525749437] 'applied index is now lower than readState.Index' (duration: 207.174µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:27.585886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.822372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:27.585949Z","caller":"traceutil/trace.go:171","msg":"trace[279349484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"176.942253ms","start":"2026-02-11T21:27:27.40898Z","end":"2026-02-11T21:27:27.585923Z","steps":["trace[279349484] 'agreement among raft nodes before linearized reading' (duration: 176.833191ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:27.58598Z","caller":"traceutil/trace.go:171","msg":"trace[49917715] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"225.75277ms","start":"2026-02-11T21:27:27.360199Z","end":"2026-02-11T21:27:27.585952Z","steps":["trace[49917715] 'process raft request' (duration: 225.393393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:28.289086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.876512ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:28.289878Z","caller":"traceutil/trace.go:171","msg":"trace[1154730579] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:747; }","duration":"473.673956ms","start":"2026-02-11T21:27:27.816185Z","end":"2026-02-11T21:27:28.289859Z","steps":["trace[1154730579] 'range keys from in-memory index tree' (duration: 472.864322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:28.374346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.312247ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-11T21:27:28.374441Z","caller":"traceutil/trace.go:171","msg":"trace[1269667803] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"666.860406ms","start":"2026-02-11T21:27:27.707564Z","end":"2026-02-11T21:27:28.374424Z","steps":["trace[1269667803] 'read index received' (duration: 255.399947ms)","trace[1269667803] 'applied index is now lower than readState.Index' (duration: 411.458979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:28.374692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.130391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T21:27:28.374723Z","caller":"traceutil/trace.go:171","msg":"trace[83381364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"667.203462ms","start":"2026-02-11T21:27:27.70751Z","end":"2026-02-11T21:27:28.374714Z","steps":["trace[83381364] 'agreement among raft nodes before linearized reading' (duration: 667.0665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:28.374986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:27.707476Z","time spent":"667.496768ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T21:27:28.375289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.392451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T21:27:28.375342Z","caller":"traceutil/trace.go:171","msg":"trace[483418328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"653.481453ms","start":"2026-02-11T21:27:27.721851Z","end":"2026-02-11T21:27:28.375332Z","steps":["trace[483418328] 'agreement among raft nodes before linearized reading' (duration: 653.353601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:28.37537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:27.721832Z","time spent":"653.531224ms","remote":"127.0.0.1:51360","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-11T21:27:28.375354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.814272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T21:27:28.37543Z","caller":"traceutil/trace.go:171","msg":"trace[1524638131] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:747; }","duration":"347.927695ms","start":"2026-02-11T21:27:28.027484Z","end":"2026-02-11T21:27:28.375411Z","steps":["trace[1524638131] 'agreement among raft nodes before linearized reading' (duration: 347.783742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:28.375473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:28.027468Z","time spent":"347.994836ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-11T21:27:28.375518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.728857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T21:27:28.375547Z","caller":"traceutil/trace.go:171","msg":"trace[1135402820] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:747; }","duration":"490.759597ms","start":"2026-02-11T21:27:27.884779Z","end":"2026-02-11T21:27:28.375539Z","steps":["trace[1135402820] 'agreement among raft nodes before linearized reading' (duration: 490.712056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:28.375573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:27.884716Z","time spent":"490.850129ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T21:27:28.375845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.004036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:28.375909Z","caller":"traceutil/trace.go:171","msg":"trace[527075341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"571.088597ms","start":"2026-02-11T21:27:27.80481Z","end":"2026-02-11T21:27:28.375898Z","steps":["trace[527075341] 'agreement among raft nodes before linearized reading' (duration: 571.006595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:28.375964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:27.804797Z","time spent":"571.158008ms","remote":"127.0.0.1:51100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-11T21:27:28.485423Z","caller":"traceutil/trace.go:171","msg":"trace[268909217] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"100.06271ms","start":"2026-02-11T21:27:28.385341Z","end":"2026-02-11T21:27:28.485404Z","steps":["trace[268909217] 'process raft request' (duration: 99.921118ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:43.21175Z","caller":"traceutil/trace.go:171","msg":"trace[1245357924] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"216.421295ms","start":"2026-02-11T21:27:42.995304Z","end":"2026-02-11T21:27:43.211725Z","steps":["trace[1245357924] 'process raft request' (duration: 216.246062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:44.024099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.47948ms","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-11T21:27:44.024167Z","caller":"traceutil/trace.go:171","msg":"trace[521275491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:845; }","duration":"368.566212ms","start":"2026-02-11T21:27:43.655578Z","end":"2026-02-11T21:27:44.024144Z","steps":["trace[521275491] 'range keys from in-memory index tree' (duration: 368.358868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:44.02419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:43.655562Z","time spent":"368.622313ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T21:27:44.935474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438029178357833,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T21:27:44.977353Z","caller":"traceutil/trace.go:171","msg":"trace[1064159133] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"949.78813ms","start":"2026-02-11T21:27:44.02755Z","end":"2026-02-11T21:27:44.977338Z","steps":["trace[1064159133] 'process raft request' (duration: 949.705008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:44.977446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:44.027534Z","time spent":"949.847891ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:27:44.977653Z","caller":"traceutil/trace.go:171","msg":"trace[2105572519] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:878; }","duration":"542.604641ms","start":"2026-02-11T21:27:44.435043Z","end":"2026-02-11T21:27:44.977648Z","steps":["trace[2105572519] 'read index received' (duration: 542.602471ms)","trace[2105572519] 'applied index is now lower than readState.Index' (duration: 1.88µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:44.977706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.504078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T21:27:44.977724Z","caller":"traceutil/trace.go:171","msg":"trace[2087170892] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:846; }","duration":"436.56229ms","start":"2026-02-11T21:27:44.541157Z","end":"2026-02-11T21:27:44.97772Z","steps":["trace[2087170892] 'agreement among raft nodes before linearized reading' (duration: 436.529259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:44.977738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:44.541138Z","time spent":"436.59639ms","remote":"127.0.0.1:51498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-11T21:27:44.977845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.800485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:44.977859Z","caller":"traceutil/trace.go:171","msg":"trace[848278607] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:846; }","duration":"542.838136ms","start":"2026-02-11T21:27:44.435017Z","end":"2026-02-11T21:27:44.977855Z","steps":["trace[848278607] 'agreement among raft nodes before linearized reading' (duration: 542.814326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:44.977871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:44.435002Z","time spent":"542.866446ms","remote":"127.0.0.1:51408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-11T21:27:44.977995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.804974ms","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-11T21:27:44.978007Z","caller":"traceutil/trace.go:171","msg":"trace[1832468916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:846; }","duration":"149.841564ms","start":"2026-02-11T21:27:44.828162Z","end":"2026-02-11T21:27:44.978003Z","steps":["trace[1832468916] 'agreement among raft nodes before linearized reading' (duration: 149.783333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:44.978239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.258591ms","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-11T21:27:44.978257Z","caller":"traceutil/trace.go:171","msg":"trace[816739593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:846; }","duration":"414.298741ms","start":"2026-02-11T21:27:44.563954Z","end":"2026-02-11T21:27:44.978253Z","steps":["trace[816739593] 'agreement among raft nodes before linearized reading' (duration: 414.2407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:44.978269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:44.563943Z","time spent":"414.323372ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T21:27:44.978351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.29859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T21:27:44.978367Z","caller":"traceutil/trace.go:171","msg":"trace[978681129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:846; }","duration":"390.33611ms","start":"2026-02-11T21:27:44.588027Z","end":"2026-02-11T21:27:44.978363Z","steps":["trace[978681129] 'agreement among raft nodes before linearized reading' (duration: 390.29738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:44.978379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:44.588016Z","time spent":"390.360221ms","remote":"127.0.0.1:51360","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-11T21:27:44.978235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.47291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:44.982637Z","caller":"traceutil/trace.go:171","msg":"trace[560815116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"175.922633ms","start":"2026-02-11T21:27:44.806699Z","end":"2026-02-11T21:27:44.982621Z","steps":["trace[560815116] 'agreement among raft nodes before linearized reading' (duration: 171.46867ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:45.23621Z","caller":"traceutil/trace.go:171","msg":"trace[862830463] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"253.996031ms","start":"2026-02-11T21:27:44.982198Z","end":"2026-02-11T21:27:45.236194Z","steps":["trace[862830463] 'process raft request' (duration: 231.10294ms)","trace[862830463] 'compare' (duration: 22.600875ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:27:45.247705Z","caller":"traceutil/trace.go:171","msg":"trace[1725096846] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:878; }","duration":"264.466618ms","start":"2026-02-11T21:27:44.983216Z","end":"2026-02-11T21:27:45.247683Z","steps":["trace[1725096846] 'read index received' (duration: 230.092652ms)","trace[1725096846] 'applied index is now lower than readState.Index' (duration: 34.373236ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:27:45.247997Z","caller":"traceutil/trace.go:171","msg":"trace[1677929427] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"265.484827ms","start":"2026-02-11T21:27:44.9825Z","end":"2026-02-11T21:27:45.247985Z","steps":["trace[1677929427] 'process raft request' (duration: 265.048079ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:45.248205Z","caller":"traceutil/trace.go:171","msg":"trace[603343163] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"263.719813ms","start":"2026-02-11T21:27:44.984473Z","end":"2026-02-11T21:27:45.248193Z","steps":["trace[603343163] 'process raft request' (duration: 263.183953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:45.253147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.114099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:45.253342Z","caller":"traceutil/trace.go:171","msg":"trace[151425272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"270.123274ms","start":"2026-02-11T21:27:44.983202Z","end":"2026-02-11T21:27:45.253325Z","steps":["trace[151425272] 'agreement among raft nodes before linearized reading' (duration: 265.107339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:52.626879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:52.317537Z","time spent":"309.339471ms","remote":"127.0.0.1:51120","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T21:27:53.051624Z","caller":"traceutil/trace.go:171","msg":"trace[1145549581] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"386.819556ms","start":"2026-02-11T21:27:52.66479Z","end":"2026-02-11T21:27:53.051609Z","steps":["trace[1145549581] 'read index received' (duration: 303.541841ms)","trace[1145549581] 'applied index is now lower than readState.Index' (duration: 83.277185ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:27:53.051689Z","caller":"traceutil/trace.go:171","msg":"trace[1159259941] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"423.661858ms","start":"2026-02-11T21:27:52.628021Z","end":"2026-02-11T21:27:53.051683Z","steps":["trace[1159259941] 'process raft request' (duration: 340.302932ms)","trace[1159259941] 'compare' (duration: 83.177112ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:53.051754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:52.627995Z","time spent":"423.706509ms","remote":"127.0.0.1:51120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:27:53.051883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.091991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:53.051903Z","caller":"traceutil/trace.go:171","msg":"trace[1802999648] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:867; }","duration":"387.131602ms","start":"2026-02-11T21:27:52.664763Z","end":"2026-02-11T21:27:53.051895Z","steps":["trace[1802999648] 'agreement among raft nodes before linearized reading' (duration: 387.087131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:53.051918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:52.66475Z","time spent":"387.164232ms","remote":"127.0.0.1:51312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-11T21:27:53.079246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.406271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:53.079295Z","caller":"traceutil/trace.go:171","msg":"trace[531111707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:868; }","duration":"276.487783ms","start":"2026-02-11T21:27:52.802795Z","end":"2026-02-11T21:27:53.079282Z","steps":["trace[531111707] 'agreement among raft nodes before linearized reading' (duration: 276.398761ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:53.07946Z","caller":"traceutil/trace.go:171","msg":"trace[697564642] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"116.786693ms","start":"2026-02-11T21:27:52.962667Z","end":"2026-02-11T21:27:53.079454Z","steps":["trace[697564642] 'process raft request' (duration: 116.379166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:53.408151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.942176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T21:27:53.408203Z","caller":"traceutil/trace.go:171","msg":"trace[950232578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"150.040318ms","start":"2026-02-11T21:27:53.25815Z","end":"2026-02-11T21:27:53.40819Z","steps":["trace[950232578] 'range keys from in-memory index tree' (duration: 149.775753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:53.408361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.439193ms","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-11T21:27:53.408379Z","caller":"traceutil/trace.go:171","msg":"trace[2072709983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:869; }","duration":"135.477484ms","start":"2026-02-11T21:27:53.272895Z","end":"2026-02-11T21:27:53.408373Z","steps":["trace[2072709983] 'range keys from in-memory index tree' (duration: 135.370652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:53.408488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.039918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T21:27:53.408503Z","caller":"traceutil/trace.go:171","msg":"trace[440201788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:869; }","duration":"125.088649ms","start":"2026-02-11T21:27:53.28341Z","end":"2026-02-11T21:27:53.408499Z","steps":["trace[440201788] 'range keys from in-memory index tree' (duration: 124.957177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:53.408597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.618083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T21:27:53.408627Z","caller":"traceutil/trace.go:171","msg":"trace[418427194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:869; }","duration":"121.659885ms","start":"2026-02-11T21:27:53.286961Z","end":"2026-02-11T21:27:53.408621Z","steps":["trace[418427194] 'range keys from in-memory index tree' (duration: 121.564323ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:56.769315Z","caller":"traceutil/trace.go:171","msg":"trace[1873931928] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"105.547253ms","start":"2026-02-11T21:27:56.663755Z","end":"2026-02-11T21:27:56.769302Z","steps":["trace[1873931928] 'read index received' (duration: 105.39475ms)","trace[1873931928] 'applied index is now lower than readState.Index' (duration: 151.993µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:56.769417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.646915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T21:27:56.769433Z","caller":"traceutil/trace.go:171","msg":"trace[1548902323] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:879; }","duration":"105.705356ms","start":"2026-02-11T21:27:56.663723Z","end":"2026-02-11T21:27:56.769428Z","steps":["trace[1548902323] 'agreement among raft nodes before linearized reading' (duration: 105.633785ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:56.76949Z","caller":"traceutil/trace.go:171","msg":"trace[345839637] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"223.795613ms","start":"2026-02-11T21:27:56.545678Z","end":"2026-02-11T21:27:56.769473Z","steps":["trace[345839637] 'process raft request' (duration: 223.508328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:57.05796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.773914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:57.058021Z","caller":"traceutil/trace.go:171","msg":"trace[1776787056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"255.861697ms","start":"2026-02-11T21:27:56.802147Z","end":"2026-02-11T21:27:57.058008Z","steps":["trace[1776787056] 'range keys from in-memory index tree' (duration: 255.740734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:57.058038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.572427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T21:27:57.058085Z","caller":"traceutil/trace.go:171","msg":"trace[832185379] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:879; }","duration":"250.639658ms","start":"2026-02-11T21:27:56.807433Z","end":"2026-02-11T21:27:57.058073Z","steps":["trace[832185379] 'count revisions from in-memory index tree' (duration: 250.537925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:57.058223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.518667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:57.058247Z","caller":"traceutil/trace.go:171","msg":"trace[1774584737] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:879; }","duration":"265.567379ms","start":"2026-02-11T21:27:56.792671Z","end":"2026-02-11T21:27:57.058238Z","steps":["trace[1774584737] 'count revisions from in-memory index tree' (duration: 265.473057ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:57.328172Z","caller":"traceutil/trace.go:171","msg":"trace[669721907] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"182.154821ms","start":"2026-02-11T21:27:57.145977Z","end":"2026-02-11T21:27:57.328132Z","steps":["trace[669721907] 'process raft request' (duration: 182.038909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:57.691897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.357389ms","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-11T21:27:57.691942Z","caller":"traceutil/trace.go:171","msg":"trace[1216867970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"237.43245ms","start":"2026-02-11T21:27:57.454499Z","end":"2026-02-11T21:27:57.691931Z","steps":["trace[1216867970] 'range keys from in-memory index tree' (duration: 237.245946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:57.692078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.642987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T21:27:57.692091Z","caller":"traceutil/trace.go:171","msg":"trace[1664210878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"185.677787ms","start":"2026-02-11T21:27:57.506409Z","end":"2026-02-11T21:27:57.692087Z","steps":["trace[1664210878] 'range keys from in-memory index tree' (duration: 185.584836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:57.69221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.139811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T21:27:57.692223Z","caller":"traceutil/trace.go:171","msg":"trace[2095128540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"182.166102ms","start":"2026-02-11T21:27:57.510053Z","end":"2026-02-11T21:27:57.692219Z","steps":["trace[2095128540] 'range keys from in-memory index tree' (duration: 182.06422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:57.692303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.392522ms","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-11T21:27:57.692316Z","caller":"traceutil/trace.go:171","msg":"trace[1433605443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"177.423712ms","start":"2026-02-11T21:27:57.514888Z","end":"2026-02-11T21:27:57.692312Z","steps":["trace[1433605443] 'range keys from in-memory index tree' (duration: 177.347152ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:57.841204Z","caller":"traceutil/trace.go:171","msg":"trace[1667459219] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"144.449613ms","start":"2026-02-11T21:27:57.696733Z","end":"2026-02-11T21:27:57.841183Z","steps":["trace[1667459219] 'process raft request' (duration: 144.297481ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:27:58.058663Z","caller":"traceutil/trace.go:171","msg":"trace[824407675] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"361.340297ms","start":"2026-02-11T21:27:57.697305Z","end":"2026-02-11T21:27:58.058645Z","steps":["trace[824407675] 'process raft request' (duration: 343.743917ms)","trace[824407675] 'compare' (duration: 17.399946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:58.058766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:57.697282Z","time spent":"361.432339ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:27:58.058962Z","caller":"traceutil/trace.go:171","msg":"trace[1789259886] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"360.96606ms","start":"2026-02-11T21:27:57.697991Z","end":"2026-02-11T21:27:58.058957Z","steps":["trace[1789259886] 'process raft request' (duration: 360.529732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:58.059192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:57.697982Z","time spent":"361.187354ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:27:58.059368Z","caller":"traceutil/trace.go:171","msg":"trace[1483507900] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"360.533591ms","start":"2026-02-11T21:27:57.69883Z","end":"2026-02-11T21:27:58.059363Z","steps":["trace[1483507900] 'process raft request' (duration: 359.713307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:27:58.059395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:27:57.698824Z","time spent":"360.556812ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:27:58.06042Z","caller":"traceutil/trace.go:171","msg":"trace[1022982247] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:915; }","duration":"256.834624ms","start":"2026-02-11T21:27:57.803571Z","end":"2026-02-11T21:27:58.060406Z","steps":["trace[1022982247] 'read index received' (duration: 38.025035ms)","trace[1022982247] 'applied index is now lower than readState.Index' (duration: 218.809079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:27:58.060453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.879855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:27:58.060466Z","caller":"traceutil/trace.go:171","msg":"trace[1122323591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"256.921646ms","start":"2026-02-11T21:27:57.803541Z","end":"2026-02-11T21:27:58.060462Z","steps":["trace[1122323591] 'agreement among raft nodes before linearized reading' (duration: 256.896006ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:28:00.447364Z","caller":"traceutil/trace.go:171","msg":"trace[6833769] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"380.368575ms","start":"2026-02-11T21:28:00.066978Z","end":"2026-02-11T21:28:00.447347Z","steps":["trace[6833769] 'process raft request' (duration: 380.273173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:00.44747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:00.066968Z","time spent":"380.447026ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:28:00.723368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.212796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:28:00.723466Z","caller":"traceutil/trace.go:171","msg":"trace[1325025324] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:922; }","duration":"602.404794ms","start":"2026-02-11T21:28:00.121052Z","end":"2026-02-11T21:28:00.723457Z","steps":["trace[1325025324] 'read index received' (duration: 326.727216ms)","trace[1325025324] 'applied index is now lower than readState.Index' (duration: 275.677108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:28:00.723528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.478156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:28:00.723545Z","caller":"traceutil/trace.go:171","msg":"trace[997431630] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:889; }","duration":"602.519917ms","start":"2026-02-11T21:28:00.12102Z","end":"2026-02-11T21:28:00.72354Z","steps":["trace[997431630] 'agreement among raft nodes before linearized reading' (duration: 602.472316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:00.723565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:00.121007Z","time spent":"602.552697ms","remote":"127.0.0.1:60600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-11T21:28:00.723742Z","caller":"traceutil/trace.go:171","msg":"trace[1477243124] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"656.655273ms","start":"2026-02-11T21:28:00.067081Z","end":"2026-02-11T21:28:00.723736Z","steps":["trace[1477243124] 'process raft request' (duration: 493.03763ms)","trace[1477243124] 'compare' (duration: 163.125925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:28:00.723776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:00.067077Z","time spent":"656.682934ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:28:00.72384Z","caller":"traceutil/trace.go:171","msg":"trace[717048060] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"650.60173ms","start":"2026-02-11T21:28:00.073234Z","end":"2026-02-11T21:28:00.723836Z","steps":["trace[717048060] 'process raft request' (duration: 650.193113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:00.723864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:00.073223Z","time spent":"650.626731ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:28:01.579906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.458318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T21:28:01.579974Z","caller":"traceutil/trace.go:171","msg":"trace[101476372] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:889; }","duration":"485.58638ms","start":"2026-02-11T21:28:01.094373Z","end":"2026-02-11T21:28:01.57996Z","steps":["trace[101476372] 'count revisions from in-memory index tree' (duration: 485.390317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:01.580015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:01.094354Z","time spent":"485.648822ms","remote":"127.0.0.1:51470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-11T21:28:01.580058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.52055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T21:28:01.580115Z","caller":"traceutil/trace.go:171","msg":"trace[967755984] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:889; }","duration":"394.624922ms","start":"2026-02-11T21:28:01.185475Z","end":"2026-02-11T21:28:01.580099Z","steps":["trace[967755984] 'count revisions from in-memory index tree' (duration: 394.435568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:01.580146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:01.185455Z","time spent":"394.681663ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-11T21:28:01.58055Z","caller":"traceutil/trace.go:171","msg":"trace[110730783] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"271.057562ms","start":"2026-02-11T21:28:01.309483Z","end":"2026-02-11T21:28:01.58054Z","steps":["trace[110730783] 'read index received' (duration: 241.799132ms)","trace[110730783] 'applied index is now lower than readState.Index' (duration: 29.25758ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:28:01.58065Z","caller":"traceutil/trace.go:171","msg":"trace[1736296179] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"275.218598ms","start":"2026-02-11T21:28:01.305424Z","end":"2026-02-11T21:28:01.580643Z","steps":["trace[1736296179] 'process raft request' (duration: 245.916218ms)","trace[1736296179] 'compare' (duration: 29.121997ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:28:01.580808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.164728ms","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-11T21:28:01.580837Z","caller":"traceutil/trace.go:171","msg":"trace[1911862285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:890; }","duration":"242.218219ms","start":"2026-02-11T21:28:01.338609Z","end":"2026-02-11T21:28:01.580827Z","steps":["trace[1911862285] 'agreement among raft nodes before linearized reading' (duration: 242.050676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:01.580896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.410038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:28:01.580918Z","caller":"traceutil/trace.go:171","msg":"trace[1941813664] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:890; }","duration":"271.463138ms","start":"2026-02-11T21:28:01.309449Z","end":"2026-02-11T21:28:01.580912Z","steps":["trace[1941813664] 'agreement among raft nodes before linearized reading' (duration: 271.418477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.039977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005032691s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:28:15.040294Z","caller":"traceutil/trace.go:171","msg":"trace[1065154863] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"541.916992ms","start":"2026-02-11T21:28:14.498148Z","end":"2026-02-11T21:28:15.040065Z","steps":["trace[1065154863] 'read index received' (duration: 72.702µs)","trace[1065154863] 'applied index is now lower than readState.Index' (duration: 541.842749ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:28:15.040567Z","caller":"traceutil/trace.go:171","msg":"trace[2012099975] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.115427632s","start":"2026-02-11T21:28:13.925125Z","end":"2026-02-11T21:28:15.040552Z","steps":["trace[2012099975] 'process raft request' (duration: 109.695409ms)","trace[2012099975] 'compare' (duration: 1.004682594s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:28:15.040689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:13.925114Z","time spent":"1.115489184s","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:28:15.040997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.847259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T21:28:15.041036Z","caller":"traceutil/trace.go:171","msg":"trace[2054862166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"542.88532ms","start":"2026-02-11T21:28:14.49814Z","end":"2026-02-11T21:28:15.041025Z","steps":["trace[2054862166] 'agreement among raft nodes before linearized reading' (duration: 542.770498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.041065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:14.498056Z","time spent":"543.003081ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T21:28:15.041425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.392492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-11T21:28:15.041464Z","caller":"traceutil/trace.go:171","msg":"trace[799589060] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:969; }","duration":"458.432482ms","start":"2026-02-11T21:28:14.583022Z","end":"2026-02-11T21:28:15.041455Z","steps":["trace[799589060] 'agreement among raft nodes before linearized reading' (duration: 458.32138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.04152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:14.582982Z","time spent":"458.504124ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-11T21:28:15.041793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.438849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:28:15.041831Z","caller":"traceutil/trace.go:171","msg":"trace[1449195669] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:969; }","duration":"212.47817ms","start":"2026-02-11T21:28:14.829342Z","end":"2026-02-11T21:28:15.04182Z","steps":["trace[1449195669] 'agreement among raft nodes before linearized reading' (duration: 212.399528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.042051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.057377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:28:15.042085Z","caller":"traceutil/trace.go:171","msg":"trace[1179098008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"237.113537ms","start":"2026-02-11T21:28:14.804963Z","end":"2026-02-11T21:28:15.042077Z","steps":["trace[1179098008] 'agreement among raft nodes before linearized reading' (duration: 237.061336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.042332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.033172ms","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-11T21:28:15.042466Z","caller":"traceutil/trace.go:171","msg":"trace[332358404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"261.208495ms","start":"2026-02-11T21:28:14.781246Z","end":"2026-02-11T21:28:15.042455Z","steps":["trace[332358404] 'agreement among raft nodes before linearized reading' (duration: 261.019312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.042698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.708072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T21:28:15.042732Z","caller":"traceutil/trace.go:171","msg":"trace[589037644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:969; }","duration":"269.780394ms","start":"2026-02-11T21:28:14.772944Z","end":"2026-02-11T21:28:15.042724Z","steps":["trace[589037644] 'agreement among raft nodes before linearized reading' (duration: 269.694682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.484775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.793592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:28:15.484852Z","caller":"traceutil/trace.go:171","msg":"trace[1225269338] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"434.5112ms","start":"2026-02-11T21:28:15.050327Z","end":"2026-02-11T21:28:15.484838Z","steps":["trace[1225269338] 'process raft request' (duration: 320.601247ms)","trace[1225269338] 'compare' (duration: 113.720009ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:28:15.484891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:15.050316Z","time spent":"434.55772ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:28:15.486715Z","caller":"traceutil/trace.go:171","msg":"trace[630074819] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"434.190213ms","start":"2026-02-11T21:28:15.052514Z","end":"2026-02-11T21:28:15.486704Z","steps":["trace[630074819] 'process raft request' (duration: 434.155362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.486764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:15.05251Z","time spent":"434.227674ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:28:15.486938Z","caller":"traceutil/trace.go:171","msg":"trace[1355081501] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"434.483968ms","start":"2026-02-11T21:28:15.052447Z","end":"2026-02-11T21:28:15.486931Z","steps":["trace[1355081501] 'process raft request' (duration: 434.104571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.487021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:15.052438Z","time spent":"434.559449ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:28:15.487131Z","caller":"traceutil/trace.go:171","msg":"trace[1370338457] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"434.663351ms","start":"2026-02-11T21:28:15.05246Z","end":"2026-02-11T21:28:15.487123Z","steps":["trace[1370338457] 'process raft request' (duration: 434.184313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:28:15.487173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:28:15.052445Z","time spent":"434.708812ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:31:28.490812Z","caller":"traceutil/trace.go:171","msg":"trace[1742111420] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"102.396213ms","start":"2026-02-11T21:31:28.385972Z","end":"2026-02-11T21:31:28.488369Z","steps":["trace[1742111420] 'process raft request' (duration: 102.26616ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:31:28.755582Z","caller":"traceutil/trace.go:171","msg":"trace[1413161292] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"180.937152ms","start":"2026-02-11T21:31:28.574613Z","end":"2026-02-11T21:31:28.75555Z","steps":["trace[1413161292] 'process raft request' (duration: 112.334357ms)","trace[1413161292] 'compare' (duration: 68.442172ms)"],"step_count":2}