{"level":"warn","ts":"2026-02-25T19:27:27.164253Z","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-25T19:27:27.16436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.11:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.11:2380","--initial-cluster=instance=https://199.19.213.11:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.11:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.11: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-25T19:27:27.164446Z","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-25T19:27:27.164462Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-02-25T19:27:27.164488Z","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-25T19:27:27.165418Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"]} {"level":"info","ts":"2026-02-25T19:27:27.165517Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.11: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-25T19:27:27.170413Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.628123ms"} {"level":"info","ts":"2026-02-25T19:27:27.176897Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ce8ad90e35537ee3","cluster-id":"3c17b62c6529d987"} {"level":"info","ts":"2026-02-25T19:27:27.176982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:27:27.177007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 0"} {"level":"info","ts":"2026-02-25T19:27:27.177022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ce8ad90e35537ee3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:27:27.17703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 1"} {"level":"info","ts":"2026-02-25T19:27:27.17707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"warn","ts":"2026-02-25T19:27:27.183067Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:27:27.186743Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:27:27.188247Z","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-25T19:27:27.190598Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ce8ad90e35537ee3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:27:27.190906Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ce8ad90e35537ee3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:27:27.190939Z","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-25T19:27:27.19111Z","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-25T19:27:27.191164Z","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-25T19:27:27.192059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"info","ts":"2026-02-25T19:27:27.192276Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","added-peer-id":"ce8ad90e35537ee3","added-peer-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-02-25T19:27:27.194228Z","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-25T19:27:27.194369Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ce8ad90e35537ee3","initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:27:27.194389Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:27:27.1948Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-02-25T19:27:27.194825Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-02-25T19:27:28.177319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:27:28.177433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:27:28.177488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgPreVoteResp from ce8ad90e35537ee3 at term 1"} {"level":"info","ts":"2026-02-25T19:27:28.177694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:27:28.177804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgVoteResp from ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-02-25T19:27:28.177831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became leader at term 2"} {"level":"info","ts":"2026-02-25T19:27:28.177856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ce8ad90e35537ee3 elected leader ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-02-25T19:27:28.178784Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:28.179379Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:27:28.179397Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:27:28.179377Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ce8ad90e35537ee3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.11:2379]}","request-path":"/0/members/ce8ad90e35537ee3/attributes","cluster-id":"3c17b62c6529d987","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:27:28.179722Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:27:28.179771Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:27:28.179873Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:28.179996Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:28.180031Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:28.181025Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:27:28.183887Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.11:2379"} {"level":"warn","ts":"2026-02-25T19:27:30.73339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.069975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:27:30.733532Z","caller":"traceutil/trace.go:171","msg":"trace[1337245069] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"203.270799ms","start":"2026-02-25T19:27:30.530243Z","end":"2026-02-25T19:27:30.733514Z","steps":["trace[1337245069] 'read index received' (duration: 29.576µs)","trace[1337245069] 'applied index is now lower than readState.Index' (duration: 203.239833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:30.733677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.428136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:30.733711Z","caller":"traceutil/trace.go:171","msg":"trace[303083822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:83; }","duration":"203.502826ms","start":"2026-02-25T19:27:30.530199Z","end":"2026-02-25T19:27:30.733702Z","steps":["trace[303083822] 'agreement among raft nodes before linearized reading' (duration: 203.369535ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:30.733845Z","caller":"traceutil/trace.go:171","msg":"trace[963471418] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"242.620879ms","start":"2026-02-25T19:27:30.491156Z","end":"2026-02-25T19:27:30.733777Z","steps":["trace[963471418] 'process raft request' (duration: 25.560978ms)","trace[963471418] 'compare' (duration: 215.939004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:31.96542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143323837713205753,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:27:32.466517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143323837713205753,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:27:32.967645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143323837713205753,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:27:33.199606Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.459405732s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:27:33.200104Z","caller":"traceutil/trace.go:171","msg":"trace[68203115] linearizableReadLoop","detail":"{readStateIndex:88; appliedIndex:87; }","duration":"1.735587704s","start":"2026-02-25T19:27:31.464484Z","end":"2026-02-25T19:27:33.200072Z","steps":["trace[68203115] 'read index received' (duration: 1.735327921s)","trace[68203115] 'applied index is now lower than readState.Index' (duration: 258.524µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:33.200245Z","caller":"traceutil/trace.go:171","msg":"trace[1430694203] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"2.460074348s","start":"2026-02-25T19:27:30.740108Z","end":"2026-02-25T19:27:33.200183Z","steps":["trace[1430694203] 'process raft request' (duration: 2.459821334s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:33.200373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669477319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:27:33.200372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735868234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:33.200422Z","caller":"traceutil/trace.go:171","msg":"trace[1395240645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:84; }","duration":"1.66960722s","start":"2026-02-25T19:27:31.5308Z","end":"2026-02-25T19:27:33.200407Z","steps":["trace[1395240645] 'agreement among raft nodes before linearized reading' (duration: 1.669430395s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:33.200439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.816007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:33.20047Z","caller":"traceutil/trace.go:171","msg":"trace[1486905788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:84; }","duration":"1.736033491s","start":"2026-02-25T19:27:31.464415Z","end":"2026-02-25T19:27:33.200448Z","steps":["trace[1486905788] 'agreement among raft nodes before linearized reading' (duration: 1.735790306s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:33.20051Z","caller":"traceutil/trace.go:171","msg":"trace[1006142205] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:84; }","duration":"925.890687ms","start":"2026-02-25T19:27:32.274584Z","end":"2026-02-25T19:27:33.200475Z","steps":["trace[1006142205] 'agreement among raft nodes before linearized reading' (duration: 925.772883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:33.200473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.530776Z","time spent":"1.669687469s","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:27:33.200528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.464383Z","time spent":"1.736136556s","remote":"127.0.0.1:45218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:27:33.200488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.74009Z","time spent":"2.460299836s","remote":"127.0.0.1:45546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:33.200568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:32.274528Z","time spent":"926.017589ms","remote":"127.0.0.1:45344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"info","ts":"2026-02-25T19:27:33.382805Z","caller":"traceutil/trace.go:171","msg":"trace[432790836] linearizableReadLoop","detail":"{readStateIndex:89; appliedIndex:88; }","duration":"178.366546ms","start":"2026-02-25T19:27:33.204414Z","end":"2026-02-25T19:27:33.38278Z","steps":["trace[432790836] 'read index received' (duration: 177.678023ms)","trace[432790836] 'applied index is now lower than readState.Index' (duration: 687.173µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:33.382953Z","caller":"traceutil/trace.go:171","msg":"trace[1386624206] transaction","detail":"{read_only:false; response_revision:85; number_of_response:1; }","duration":"179.476239ms","start":"2026-02-25T19:27:33.203395Z","end":"2026-02-25T19:27:33.382871Z","steps":["trace[1386624206] 'process raft request' (duration: 178.793445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:33.383074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.126376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:33.383185Z","caller":"traceutil/trace.go:171","msg":"trace[1661463153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:85; }","duration":"153.224692ms","start":"2026-02-25T19:27:33.229906Z","end":"2026-02-25T19:27:33.38313Z","steps":["trace[1661463153] 'agreement among raft nodes before linearized reading' (duration: 153.058046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:33.383103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.655625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:33.383416Z","caller":"traceutil/trace.go:171","msg":"trace[152948816] range","detail":"{range_begin:/registry/clusterroles/system:node; range_end:; response_count:0; response_revision:85; }","duration":"179.056778ms","start":"2026-02-25T19:27:33.204335Z","end":"2026-02-25T19:27:33.383392Z","steps":["trace[152948816] 'agreement among raft nodes before linearized reading' (duration: 178.561328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:34.16536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.086941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:34.165692Z","caller":"traceutil/trace.go:171","msg":"trace[1115530079] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"213.599008ms","start":"2026-02-25T19:27:33.952051Z","end":"2026-02-25T19:27:34.16565Z","steps":["trace[1115530079] 'process raft request' (duration: 82.153476ms)","trace[1115530079] 'compare' (duration: 130.872592ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:34.359832Z","caller":"traceutil/trace.go:171","msg":"trace[967133547] linearizableReadLoop","detail":"{readStateIndex:181; appliedIndex:180; }","duration":"129.702024ms","start":"2026-02-25T19:27:34.230101Z","end":"2026-02-25T19:27:34.359804Z","steps":["trace[967133547] 'read index received' (duration: 126.112405ms)","trace[967133547] 'applied index is now lower than readState.Index' (duration: 3.587819ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:34.360253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.130104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:34.360363Z","caller":"traceutil/trace.go:171","msg":"trace[485831662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:177; }","duration":"130.272904ms","start":"2026-02-25T19:27:34.230074Z","end":"2026-02-25T19:27:34.360346Z","steps":["trace[485831662] 'agreement among raft nodes before linearized reading' (duration: 130.093889ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:34.360254Z","caller":"traceutil/trace.go:171","msg":"trace[302445477] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"145.77079ms","start":"2026-02-25T19:27:34.214449Z","end":"2026-02-25T19:27:34.36022Z","steps":["trace[302445477] 'process raft request' (duration: 141.818822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:34.581365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.319369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:34.581513Z","caller":"traceutil/trace.go:171","msg":"trace[626295499] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"130.518231ms","start":"2026-02-25T19:27:34.450967Z","end":"2026-02-25T19:27:34.581485Z","steps":["trace[626295499] 'process raft request' (duration: 29.9953ms)","trace[626295499] 'compare' (duration: 100.121867ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:34.800201Z","caller":"traceutil/trace.go:171","msg":"trace[272640842] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"211.759865ms","start":"2026-02-25T19:27:34.588326Z","end":"2026-02-25T19:27:34.800086Z","steps":["trace[272640842] 'process raft request' (duration: 170.55282ms)","trace[272640842] 'compare' (duration: 41.052096ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:35.003498Z","caller":"traceutil/trace.go:171","msg":"trace[2053526335] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"120.080879ms","start":"2026-02-25T19:27:34.883392Z","end":"2026-02-25T19:27:35.003473Z","steps":["trace[2053526335] 'process raft request' (duration: 44.24309ms)","trace[2053526335] 'compare' (duration: 75.714126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:35.530254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.093856ms","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-25T19:27:35.530349Z","caller":"traceutil/trace.go:171","msg":"trace[445210490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:193; }","duration":"128.229777ms","start":"2026-02-25T19:27:35.402099Z","end":"2026-02-25T19:27:35.530328Z","steps":["trace[445210490] 'range keys from in-memory index tree' (duration: 127.947796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:35.530381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.331295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T19:27:35.530458Z","caller":"traceutil/trace.go:171","msg":"trace[920265336] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:193; }","duration":"129.457948ms","start":"2026-02-25T19:27:35.400982Z","end":"2026-02-25T19:27:35.53044Z","steps":["trace[920265336] 'range keys from in-memory index tree' (duration: 129.092458ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:00.235325Z","caller":"traceutil/trace.go:171","msg":"trace[166196109] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"228.283007ms","start":"2026-02-25T19:28:00.007026Z","end":"2026-02-25T19:28:00.235309Z","steps":["trace[166196109] 'process raft request' (duration: 227.989041ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:04.370504Z","caller":"traceutil/trace.go:171","msg":"trace[393067869] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"643.950202ms","start":"2026-02-25T19:28:03.726516Z","end":"2026-02-25T19:28:04.370466Z","steps":["trace[393067869] 'process raft request' (duration: 643.729907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:04.371013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:03.726507Z","time spent":"644.110504ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:04.374033Z","caller":"traceutil/trace.go:171","msg":"trace[1166126975] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:551; }","duration":"214.438201ms","start":"2026-02-25T19:28:04.159574Z","end":"2026-02-25T19:28:04.374012Z","steps":["trace[1166126975] 'read index received' (duration: 210.655158ms)","trace[1166126975] 'applied index is now lower than readState.Index' (duration: 3.782133ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:04.374131Z","caller":"traceutil/trace.go:171","msg":"trace[2009850713] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"646.299528ms","start":"2026-02-25T19:28:03.727821Z","end":"2026-02-25T19:28:04.37412Z","steps":["trace[2009850713] 'process raft request' (duration: 646.065494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:04.374589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.990419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:04.374718Z","caller":"traceutil/trace.go:171","msg":"trace[981101377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"215.144532ms","start":"2026-02-25T19:28:04.159521Z","end":"2026-02-25T19:28:04.374666Z","steps":["trace[981101377] 'agreement among raft nodes before linearized reading' (duration: 214.898479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:04.374887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.408908ms","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-25T19:28:04.37542Z","caller":"traceutil/trace.go:171","msg":"trace[1318017347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"130.57146ms","start":"2026-02-25T19:28:04.244391Z","end":"2026-02-25T19:28:04.374962Z","steps":["trace[1318017347] 'agreement among raft nodes before linearized reading' (duration: 130.04162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:04.37946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:03.727809Z","time spent":"646.348893ms","remote":"127.0.0.1:45472","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-25T19:28:04.853327Z","caller":"traceutil/trace.go:171","msg":"trace[1589461106] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"196.022336ms","start":"2026-02-25T19:28:04.657279Z","end":"2026-02-25T19:28:04.853302Z","steps":["trace[1589461106] 'process raft request' (duration: 195.892551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:06.120511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:05.633491Z","time spent":"487.015782ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T19:28:06.658697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143323837713206684,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:28:06.915044Z","caller":"traceutil/trace.go:171","msg":"trace[1925451771] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"756.677281ms","start":"2026-02-25T19:28:06.158345Z","end":"2026-02-25T19:28:06.915022Z","steps":["trace[1925451771] 'read index received' (duration: 747.404776ms)","trace[1925451771] 'applied index is now lower than readState.Index' (duration: 9.270845ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:06.915064Z","caller":"traceutil/trace.go:171","msg":"trace[1362392939] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"793.51641ms","start":"2026-02-25T19:28:06.12152Z","end":"2026-02-25T19:28:06.915036Z","steps":["trace[1362392939] 'process raft request' (duration: 784.255314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:06.915198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:06.121496Z","time spent":"793.629817ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:06.915427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.483682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T19:28:06.915473Z","caller":"traceutil/trace.go:171","msg":"trace[606351334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"540.578342ms","start":"2026-02-25T19:28:06.374883Z","end":"2026-02-25T19:28:06.915461Z","steps":["trace[606351334] 'agreement among raft nodes before linearized reading' (duration: 540.432058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:06.915473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.155835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:28:06.915498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:06.374863Z","time spent":"540.630926ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:28:06.915515Z","caller":"traceutil/trace.go:171","msg":"trace[956604497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"530.217368ms","start":"2026-02-25T19:28:06.385284Z","end":"2026-02-25T19:28:06.915502Z","steps":["trace[956604497] 'agreement among raft nodes before linearized reading' (duration: 530.054216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:06.915548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:06.385274Z","time spent":"530.267623ms","remote":"127.0.0.1:45472","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-25T19:28:06.915659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.43907ms","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-25T19:28:06.915742Z","caller":"traceutil/trace.go:171","msg":"trace[543372175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"532.557177ms","start":"2026-02-25T19:28:06.383165Z","end":"2026-02-25T19:28:06.915722Z","steps":["trace[543372175] 'agreement among raft nodes before linearized reading' (duration: 532.292256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:06.915786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:06.383153Z","time spent":"532.625079ms","remote":"127.0.0.1:45472","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-25T19:28:06.915225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.861111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:06.915929Z","caller":"traceutil/trace.go:171","msg":"trace[1407241692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"757.536076ms","start":"2026-02-25T19:28:06.158321Z","end":"2026-02-25T19:28:06.915857Z","steps":["trace[1407241692] 'agreement among raft nodes before linearized reading' (duration: 756.822355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:06.915975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:06.158311Z","time spent":"757.650223ms","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:12.695118Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.076977477s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:28:12.695486Z","caller":"traceutil/trace.go:171","msg":"trace[756209397] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.077460817s","start":"2026-02-25T19:28:11.618003Z","end":"2026-02-25T19:28:12.695464Z","steps":["trace[756209397] 'process raft request' (duration: 1.077266564s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:12.695643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:11.617954Z","time spent":"1.077607819s","remote":"127.0.0.1:45404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:13.601175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.261609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:13.601376Z","caller":"traceutil/trace.go:171","msg":"trace[702916232] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"442.494349ms","start":"2026-02-25T19:28:13.158865Z","end":"2026-02-25T19:28:13.60136Z","steps":["trace[702916232] 'read index received' (duration: 153.880552ms)","trace[702916232] 'applied index is now lower than readState.Index' (duration: 288.612447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:13.601448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.586464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:13.601474Z","caller":"traceutil/trace.go:171","msg":"trace[492555952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"442.651674ms","start":"2026-02-25T19:28:13.158813Z","end":"2026-02-25T19:28:13.601465Z","steps":["trace[492555952] 'agreement among raft nodes before linearized reading' (duration: 442.596695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:13.601513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:13.158793Z","time spent":"442.704084ms","remote":"127.0.0.1:45218","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-25T19:28:13.601589Z","caller":"traceutil/trace.go:171","msg":"trace[2095624734] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"530.200748ms","start":"2026-02-25T19:28:13.071369Z","end":"2026-02-25T19:28:13.60157Z","steps":["trace[2095624734] 'process raft request' (duration: 241.467872ms)","trace[2095624734] 'compare' (duration: 288.132987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:13.601669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:13.071351Z","time spent":"530.277241ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:13.601722Z","caller":"traceutil/trace.go:171","msg":"trace[404181010] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"530.08718ms","start":"2026-02-25T19:28:13.071623Z","end":"2026-02-25T19:28:13.60171Z","steps":["trace[404181010] 'process raft request' (duration: 529.66505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:13.60178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:13.071609Z","time spent":"530.138149ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:15.591061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143323837713206775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:16.092206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143323837713206775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:16.520138Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.445056393s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:28:16.520545Z","caller":"traceutil/trace.go:171","msg":"trace[1625294551] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"1.430252339s","start":"2026-02-25T19:28:15.09027Z","end":"2026-02-25T19:28:16.520522Z","steps":["trace[1625294551] 'read index received' (duration: 1.429990299s)","trace[1625294551] 'applied index is now lower than readState.Index' (duration: 260.73µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:16.520578Z","caller":"traceutil/trace.go:171","msg":"trace[259825020] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.445624911s","start":"2026-02-25T19:28:15.074938Z","end":"2026-02-25T19:28:16.520563Z","steps":["trace[259825020] 'process raft request' (duration: 1.445414088s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:16.520673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:15.074915Z","time spent":"1.445695462s","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:16.520846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430547935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:28:16.520841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.741427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T19:28:16.520868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.081133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.11\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T19:28:16.520907Z","caller":"traceutil/trace.go:171","msg":"trace[1533575093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"915.828641ms","start":"2026-02-25T19:28:15.60506Z","end":"2026-02-25T19:28:16.520889Z","steps":["trace[1533575093] 'agreement among raft nodes before linearized reading' (duration: 915.671817ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:16.520895Z","caller":"traceutil/trace.go:171","msg":"trace[1041047858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"1.430656312s","start":"2026-02-25T19:28:15.090227Z","end":"2026-02-25T19:28:16.520883Z","steps":["trace[1041047858] 'agreement among raft nodes before linearized reading' (duration: 1.430388501s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:16.520955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:15.605052Z","time spent":"915.894612ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:16.520842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.085492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T19:28:16.52098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.359514172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:16.520962Z","caller":"traceutil/trace.go:171","msg":"trace[2106498833] range","detail":"{range_begin:/registry/masterleases/199.19.213.11; range_end:; response_count:1; response_revision:581; }","duration":"888.201722ms","start":"2026-02-25T19:28:15.632725Z","end":"2026-02-25T19:28:16.520927Z","steps":["trace[2106498833] 'agreement among raft nodes before linearized reading' (duration: 888.028665ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:16.521005Z","caller":"traceutil/trace.go:171","msg":"trace[2022115058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"1.359829211s","start":"2026-02-25T19:28:15.161166Z","end":"2026-02-25T19:28:16.520996Z","steps":["trace[2022115058] 'agreement among raft nodes before linearized reading' (duration: 1.359526064s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:16.521027Z","caller":"traceutil/trace.go:171","msg":"trace[959001677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"916.293354ms","start":"2026-02-25T19:28:15.604695Z","end":"2026-02-25T19:28:16.520989Z","steps":["trace[959001677] 'agreement among raft nodes before linearized reading' (duration: 916.054408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:16.521058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:15.161146Z","time spent":"1.359906962s","remote":"127.0.0.1:45218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:16.521075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:15.604677Z","time spent":"916.388209ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:28:16.520963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:15.090205Z","time spent":"1.430746476s","remote":"127.0.0.1:45472","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-25T19:28:16.521022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:15.632711Z","time spent":"888.300837ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.11\" "} {"level":"warn","ts":"2026-02-25T19:28:17.024083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143323837713206778,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:17.699159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.706768ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-25T19:28:17.700211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:16.522625Z","time spent":"1.177576857s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T19:28:17.707465Z","caller":"traceutil/trace.go:171","msg":"trace[1409343780] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.182517166s","start":"2026-02-25T19:28:16.524927Z","end":"2026-02-25T19:28:17.707444Z","steps":["trace[1409343780] 'process raft request' (duration: 1.182464428s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:17.707487Z","caller":"traceutil/trace.go:171","msg":"trace[1207956202] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.182809861s","start":"2026-02-25T19:28:16.524646Z","end":"2026-02-25T19:28:17.707456Z","steps":["trace[1207956202] 'process raft request' (duration: 1.182570055s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:17.707593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:16.524916Z","time spent":"1.182613431s","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:17.707639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:16.524631Z","time spent":"1.182926019s","remote":"127.0.0.1:45472","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-25T19:28:17.70771Z","caller":"traceutil/trace.go:171","msg":"trace[45805762] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:604; }","duration":"1.183749256s","start":"2026-02-25T19:28:16.523906Z","end":"2026-02-25T19:28:17.707655Z","steps":["trace[45805762] 'read index received' (duration: 989.625875ms)","trace[45805762] 'applied index is now lower than readState.Index' (duration: 194.12087ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:17.707741Z","caller":"traceutil/trace.go:171","msg":"trace[1973801654] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.182068296s","start":"2026-02-25T19:28:16.525647Z","end":"2026-02-25T19:28:17.707715Z","steps":["trace[1973801654] 'process raft request' (duration: 1.181770051s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:17.707799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183885407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:17.707847Z","caller":"traceutil/trace.go:171","msg":"trace[547323033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"1.183960558s","start":"2026-02-25T19:28:16.523873Z","end":"2026-02-25T19:28:17.707834Z","steps":["trace[547323033] 'agreement among raft nodes before linearized reading' (duration: 1.183887446s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:17.707879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:16.523856Z","time spent":"1.184016046s","remote":"127.0.0.1:45234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:17.70788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:16.525632Z","time spent":"1.182159399s","remote":"127.0.0.1:45472","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-25T19:28:17.708084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.290732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:17.708122Z","caller":"traceutil/trace.go:171","msg":"trace[447560314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"627.345071ms","start":"2026-02-25T19:28:17.080767Z","end":"2026-02-25T19:28:17.708112Z","steps":["trace[447560314] 'agreement among raft nodes before linearized reading' (duration: 627.27161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:17.708142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:17.080754Z","time spent":"627.384137ms","remote":"127.0.0.1:45218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:19.482296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.510203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:19.482374Z","caller":"traceutil/trace.go:171","msg":"trace[1361526731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"321.647633ms","start":"2026-02-25T19:28:19.160708Z","end":"2026-02-25T19:28:19.482356Z","steps":["trace[1361526731] 'range keys from in-memory index tree' (duration: 321.407118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:19.482414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:19.160689Z","time spent":"321.716733ms","remote":"127.0.0.1:45234","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-25T19:28:20.259348Z","caller":"traceutil/trace.go:171","msg":"trace[1672643544] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"188.549616ms","start":"2026-02-25T19:28:20.070778Z","end":"2026-02-25T19:28:20.259328Z","steps":["trace[1672643544] 'process raft request' (duration: 111.137129ms)","trace[1672643544] 'compare' (duration: 77.282068ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:20.431727Z","caller":"traceutil/trace.go:171","msg":"trace[1902470500] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"161.078928ms","start":"2026-02-25T19:28:20.270615Z","end":"2026-02-25T19:28:20.431693Z","steps":["trace[1902470500] 'process raft request' (duration: 160.494425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:20.432102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.705187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:20.432247Z","caller":"traceutil/trace.go:171","msg":"trace[80431754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:601; }","duration":"153.842736ms","start":"2026-02-25T19:28:20.27832Z","end":"2026-02-25T19:28:20.432162Z","steps":["trace[80431754] 'agreement among raft nodes before linearized reading' (duration: 153.497797ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:20.432275Z","caller":"traceutil/trace.go:171","msg":"trace[462970495] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"153.272316ms","start":"2026-02-25T19:28:20.278365Z","end":"2026-02-25T19:28:20.431637Z","steps":["trace[462970495] 'read index received' (duration: 152.795889ms)","trace[462970495] 'applied index is now lower than readState.Index' (duration: 475.027µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:21.618641Z","caller":"traceutil/trace.go:171","msg":"trace[1130381607] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"105.673884ms","start":"2026-02-25T19:28:21.512948Z","end":"2026-02-25T19:28:21.618622Z","steps":["trace[1130381607] 'process raft request' (duration: 98.082788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:22.07025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.644071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:22.070309Z","caller":"traceutil/trace.go:171","msg":"trace[781357030] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:642; }","duration":"202.736684ms","start":"2026-02-25T19:28:21.867557Z","end":"2026-02-25T19:28:22.070294Z","steps":["trace[781357030] 'range keys from in-memory index tree' (duration: 202.575561ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:22.070461Z","caller":"traceutil/trace.go:171","msg":"trace[1330961127] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"107.565356ms","start":"2026-02-25T19:28:21.962861Z","end":"2026-02-25T19:28:22.070426Z","steps":["trace[1330961127] 'process raft request' (duration: 104.746487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:23.108958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.455078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:23.109085Z","caller":"traceutil/trace.go:171","msg":"trace[382137670] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"258.471718ms","start":"2026-02-25T19:28:22.850591Z","end":"2026-02-25T19:28:23.109063Z","steps":["trace[382137670] 'read index received' (duration: 5.755185ms)","trace[382137670] 'applied index is now lower than readState.Index' (duration: 252.714812ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:23.10922Z","caller":"traceutil/trace.go:171","msg":"trace[356012915] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"317.067732ms","start":"2026-02-25T19:28:22.792133Z","end":"2026-02-25T19:28:23.109201Z","steps":["trace[356012915] 'process raft request' (duration: 64.287241ms)","trace[356012915] 'compare' (duration: 252.287936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:23.109306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:22.792101Z","time spent":"317.156864ms","remote":"127.0.0.1:45274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:28:23.109884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.289286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-25T19:28:23.109947Z","caller":"traceutil/trace.go:171","msg":"trace[546782017] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:652; }","duration":"259.407771ms","start":"2026-02-25T19:28:22.850521Z","end":"2026-02-25T19:28:23.109929Z","steps":["trace[546782017] 'agreement among raft nodes before linearized reading' (duration: 258.913626ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:38.184035Z","caller":"traceutil/trace.go:171","msg":"trace[181043827] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"209.925405ms","start":"2026-02-25T19:28:37.97409Z","end":"2026-02-25T19:28:38.184015Z","steps":["trace[181043827] 'read index received' (duration: 209.732798ms)","trace[181043827] 'applied index is now lower than readState.Index' (duration: 191.777µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:38.18413Z","caller":"traceutil/trace.go:171","msg":"trace[1377662721] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"304.394148ms","start":"2026-02-25T19:28:37.879709Z","end":"2026-02-25T19:28:38.184103Z","steps":["trace[1377662721] 'process raft request' (duration: 304.176318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:38.18435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.261326ms","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-25T19:28:38.184404Z","caller":"traceutil/trace.go:171","msg":"trace[1150831959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"210.346383ms","start":"2026-02-25T19:28:37.974048Z","end":"2026-02-25T19:28:38.184394Z","steps":["trace[1150831959] 'agreement among raft nodes before linearized reading' (duration: 210.0946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:38.184285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.565056ms","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-25T19:28:38.184571Z","caller":"traceutil/trace.go:171","msg":"trace[700228183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"129.82273ms","start":"2026-02-25T19:28:38.054655Z","end":"2026-02-25T19:28:38.184478Z","steps":["trace[700228183] 'agreement among raft nodes before linearized reading' (duration: 129.524273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:38.184518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:37.879694Z","time spent":"304.638961ms","remote":"127.0.0.1:45472","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-25T19:29:46.788531Z","caller":"traceutil/trace.go:171","msg":"trace[1347577119] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"150.940027ms","start":"2026-02-25T19:29:46.63755Z","end":"2026-02-25T19:29:46.78849Z","steps":["trace[1347577119] 'process raft request' (duration: 111.836275ms)","trace[1347577119] 'compare' (duration: 38.852985ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:29:47.382359Z","caller":"traceutil/trace.go:171","msg":"trace[1200527892] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"154.440355ms","start":"2026-02-25T19:29:47.227871Z","end":"2026-02-25T19:29:47.382311Z","steps":["trace[1200527892] 'read index received' (duration: 146.660231ms)","trace[1200527892] 'applied index is now lower than readState.Index' (duration: 7.778334ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:29:47.382393Z","caller":"traceutil/trace.go:171","msg":"trace[887272790] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"589.138493ms","start":"2026-02-25T19:29:46.793206Z","end":"2026-02-25T19:29:47.382345Z","steps":["trace[887272790] 'process raft request' (duration: 581.372258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:47.382543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.654942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:29:47.382625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:46.793184Z","time spent":"589.316861ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:29:47.382658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.533203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T19:29:47.382901Z","caller":"traceutil/trace.go:171","msg":"trace[775679382] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1026; }","duration":"154.83482ms","start":"2026-02-25T19:29:47.227823Z","end":"2026-02-25T19:29:47.382658Z","steps":["trace[775679382] 'agreement among raft nodes before linearized reading' (duration: 154.662122ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:47.382908Z","caller":"traceutil/trace.go:171","msg":"trace[1412161021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"115.816888ms","start":"2026-02-25T19:29:47.267081Z","end":"2026-02-25T19:29:47.382898Z","steps":["trace[1412161021] 'agreement among raft nodes before linearized reading' (duration: 115.440744ms)"],"step_count":1}