{"level":"warn","ts":"2026-07-02T15:24:50.458931Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:24:50.45902Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.82: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.82:2380","--initial-cluster=instance=https://199.19.213.82:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.82:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.82: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-07-02T15:24:50.459087Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T15:24:50.459094Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.82:2380"]} {"level":"info","ts":"2026-07-02T15:24:50.459114Z","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-07-02T15:24:50.459738Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.82:2379"]} {"level":"info","ts":"2026-07-02T15:24:50.459806Z","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.82:2380"],"listen-peer-urls":["https://199.19.213.82:2380"],"advertise-client-urls":["https://199.19.213.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.82: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-07-02T15:24:50.464401Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.396419ms"} {"level":"info","ts":"2026-07-02T15:24:50.470736Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5d464d3ed167a141","cluster-id":"2ab4f6fe2538e5aa"} {"level":"info","ts":"2026-07-02T15:24:50.470835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T15:24:50.47086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became follower at term 0"} {"level":"info","ts":"2026-07-02T15:24:50.470886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5d464d3ed167a141 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T15:24:50.470903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became follower at term 1"} {"level":"info","ts":"2026-07-02T15:24:50.470956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 switched to configuration voters=(6721144426093584705)"} {"level":"warn","ts":"2026-07-02T15:24:50.475087Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T15:24:50.476604Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T15:24:50.477523Z","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-07-02T15:24:50.479576Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5d464d3ed167a141","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T15:24:50.479641Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5d464d3ed167a141","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T15:24:50.48039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 switched to configuration voters=(6721144426093584705)"} {"level":"info","ts":"2026-07-02T15:24:50.480477Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2ab4f6fe2538e5aa","local-member-id":"5d464d3ed167a141","added-peer-id":"5d464d3ed167a141","added-peer-peer-urls":["https://199.19.213.82:2380"]} {"level":"info","ts":"2026-07-02T15:24:50.480582Z","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-07-02T15:24:50.480635Z","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-07-02T15:24:50.480644Z","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-07-02T15:24:50.484333Z","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-07-02T15:24:50.484448Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.82:2380"} {"level":"info","ts":"2026-07-02T15:24:50.484512Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.82:2380"} {"level":"info","ts":"2026-07-02T15:24:50.486054Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5d464d3ed167a141","initial-advertise-peer-urls":["https://199.19.213.82:2380"],"listen-peer-urls":["https://199.19.213.82:2380"],"advertise-client-urls":["https://199.19.213.82:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.82:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T15:24:50.48609Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T15:24:50.971906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T15:24:50.972173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T15:24:50.972322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 received MsgPreVoteResp from 5d464d3ed167a141 at term 1"} {"level":"info","ts":"2026-07-02T15:24:50.9727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became candidate at term 2"} {"level":"info","ts":"2026-07-02T15:24:50.972766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 received MsgVoteResp from 5d464d3ed167a141 at term 2"} {"level":"info","ts":"2026-07-02T15:24:50.97285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d464d3ed167a141 became leader at term 2"} {"level":"info","ts":"2026-07-02T15:24:50.973014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5d464d3ed167a141 elected leader 5d464d3ed167a141 at term 2"} {"level":"info","ts":"2026-07-02T15:24:50.974387Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:24:50.975256Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5d464d3ed167a141","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.82:2379]}","request-path":"/0/members/5d464d3ed167a141/attributes","cluster-id":"2ab4f6fe2538e5aa","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T15:24:50.975405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:24:50.975617Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T15:24:50.976494Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T15:24:50.975481Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T15:24:50.976439Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2ab4f6fe2538e5aa","local-member-id":"5d464d3ed167a141","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:24:50.979082Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:24:50.979209Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T15:24:50.978477Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T15:24:50.979436Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.82:2379"} {"level":"info","ts":"2026-07-02T15:25:01.807667Z","caller":"traceutil/trace.go:171","msg":"trace[643325581] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"154.925304ms","start":"2026-07-02T15:25:01.652717Z","end":"2026-07-02T15:25:01.807642Z","steps":["trace[643325581] 'process raft request' (duration: 154.75652ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:25:31.044942Z","caller":"traceutil/trace.go:171","msg":"trace[897688372] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"181.389498ms","start":"2026-07-02T15:25:30.863523Z","end":"2026-07-02T15:25:31.044912Z","steps":["trace[897688372] 'read index received' (duration: 181.163241ms)","trace[897688372] 'applied index is now lower than readState.Index' (duration: 225.577µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:25:31.045453Z","caller":"traceutil/trace.go:171","msg":"trace[1004024047] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"265.929622ms","start":"2026-07-02T15:25:30.779513Z","end":"2026-07-02T15:25:31.045442Z","steps":["trace[1004024047] 'process raft request' (duration: 265.238923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:31.045458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.702926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:25:31.045969Z","caller":"traceutil/trace.go:171","msg":"trace[447049110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"182.478497ms","start":"2026-07-02T15:25:30.863473Z","end":"2026-07-02T15:25:31.045952Z","steps":["trace[447049110] 'agreement among raft nodes before linearized reading' (duration: 181.718457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:38.330797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619743488123841745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:25:38.831114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619743488123841745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:25:39.331652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619743488123841745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:25:39.579845Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.82864888s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T15:25:39.580351Z","caller":"traceutil/trace.go:171","msg":"trace[241462457] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.829271857s","start":"2026-07-02T15:25:37.751058Z","end":"2026-07-02T15:25:39.58033Z","steps":["trace[241462457] 'process raft request' (duration: 1.829110213s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:39.580911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:37.751041Z","time spent":"1.829376369s","remote":"127.0.0.1:48956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:25:39.584799Z","caller":"traceutil/trace.go:171","msg":"trace[817139933] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:685; }","duration":"1.75480576s","start":"2026-07-02T15:25:37.829969Z","end":"2026-07-02T15:25:39.584775Z","steps":["trace[817139933] 'read index received' (duration: 1.750219327s)","trace[817139933] 'applied index is now lower than readState.Index' (duration: 4.585013ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:25:39.585006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722300884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:25:39.585044Z","caller":"traceutil/trace.go:171","msg":"trace[425939055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"1.722419207s","start":"2026-07-02T15:25:37.862616Z","end":"2026-07-02T15:25:39.585035Z","steps":["trace[425939055] 'agreement among raft nodes before linearized reading' (duration: 1.722256143s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:39.58507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:37.862587Z","time spent":"1.722477229s","remote":"127.0.0.1:48772","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-07-02T15:25:39.585183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.826756ms","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-07-02T15:25:39.585236Z","caller":"traceutil/trace.go:171","msg":"trace[758931771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"434.95629ms","start":"2026-07-02T15:25:39.150262Z","end":"2026-07-02T15:25:39.585219Z","steps":["trace[758931771] 'agreement among raft nodes before linearized reading' (duration: 434.844237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:39.58528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:39.150056Z","time spent":"435.216827ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T15:25:39.585402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.464488ms","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-07-02T15:25:39.585507Z","caller":"traceutil/trace.go:171","msg":"trace[1870611360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"869.584222ms","start":"2026-07-02T15:25:38.715913Z","end":"2026-07-02T15:25:39.585497Z","steps":["trace[1870611360] 'agreement among raft nodes before linearized reading' (duration: 869.399547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:39.58551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755514719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-02T15:25:39.585562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:38.715905Z","time spent":"869.645653ms","remote":"127.0.0.1:49022","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-07-02T15:25:39.585046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.113855ms","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-07-02T15:25:39.585585Z","caller":"traceutil/trace.go:171","msg":"trace[1590766422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:662; }","duration":"1.755653893s","start":"2026-07-02T15:25:37.829906Z","end":"2026-07-02T15:25:39.58556Z","steps":["trace[1590766422] 'agreement among raft nodes before linearized reading' (duration: 1.754955804s)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:25:39.58561Z","caller":"traceutil/trace.go:171","msg":"trace[116715809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"744.720752ms","start":"2026-07-02T15:25:38.840879Z","end":"2026-07-02T15:25:39.5856Z","steps":["trace[116715809] 'agreement among raft nodes before linearized reading' (duration: 744.003833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:39.585644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:37.829876Z","time spent":"1.755745735s","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T15:25:39.585647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:38.840866Z","time spent":"744.770903ms","remote":"127.0.0.1:49022","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-07-02T15:25:39.585782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.509966ms","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-07-02T15:25:39.585809Z","caller":"traceutil/trace.go:171","msg":"trace[293540982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"870.572868ms","start":"2026-07-02T15:25:38.715228Z","end":"2026-07-02T15:25:39.585801Z","steps":["trace[293540982] 'agreement among raft nodes before linearized reading' (duration: 870.435274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:39.585836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:38.715208Z","time spent":"870.622359ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T15:25:44.060985Z","caller":"traceutil/trace.go:171","msg":"trace[15124390] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"114.090604ms","start":"2026-07-02T15:25:43.946867Z","end":"2026-07-02T15:25:44.060957Z","steps":["trace[15124390] 'process raft request' (duration: 113.876158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:55.067779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619743488123841960,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T15:25:55.233614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270605687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.82\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T15:25:55.234205Z","caller":"traceutil/trace.go:171","msg":"trace[223592328] range","detail":"{range_begin:/registry/masterleases/199.19.213.82; range_end:; response_count:1; response_revision:777; }","duration":"1.271212744s","start":"2026-07-02T15:25:53.962973Z","end":"2026-07-02T15:25:55.234185Z","steps":["trace[223592328] 'range keys from in-memory index tree' (duration: 1.27038945s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:55.234383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:53.962901Z","time spent":"1.271451399s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.82\" "} {"level":"info","ts":"2026-07-02T15:25:55.23791Z","caller":"traceutil/trace.go:171","msg":"trace[1845003639] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"821.215773ms","start":"2026-07-02T15:25:54.416671Z","end":"2026-07-02T15:25:55.237886Z","steps":["trace[1845003639] 'process raft request' (duration: 821.08923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:55.238053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:54.416645Z","time spent":"821.334106ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:25:55.291227Z","caller":"traceutil/trace.go:171","msg":"trace[1010302552] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:805; }","duration":"723.707922ms","start":"2026-07-02T15:25:54.567497Z","end":"2026-07-02T15:25:55.291205Z","steps":["trace[1010302552] 'read index received' (duration: 670.232886ms)","trace[1010302552] 'applied index is now lower than readState.Index' (duration: 53.473926ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:25:55.291528Z","caller":"traceutil/trace.go:171","msg":"trace[1805333695] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"422.507618ms","start":"2026-07-02T15:25:54.869011Z","end":"2026-07-02T15:25:55.291518Z","steps":["trace[1805333695] 'process raft request' (duration: 422.001775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:55.291649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:54.868991Z","time spent":"422.574981ms","remote":"127.0.0.1:48992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:25:55.291974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.481042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:25:55.292016Z","caller":"traceutil/trace.go:171","msg":"trace[263278445] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:779; }","duration":"724.566384ms","start":"2026-07-02T15:25:54.567439Z","end":"2026-07-02T15:25:55.292005Z","steps":["trace[263278445] 'agreement among raft nodes before linearized reading' (duration: 724.486712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:55.292048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:54.567415Z","time spent":"724.624756ms","remote":"127.0.0.1:48816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:25:55.292255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.371364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:25:55.292288Z","caller":"traceutil/trace.go:171","msg":"trace[1128349463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"428.491297ms","start":"2026-07-02T15:25:54.863789Z","end":"2026-07-02T15:25:55.29228Z","steps":["trace[1128349463] 'agreement among raft nodes before linearized reading' (duration: 428.395755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:25:55.292313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:25:54.863769Z","time spent":"428.539099ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T15:26:24.086629Z","caller":"traceutil/trace.go:171","msg":"trace[898186283] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"122.172749ms","start":"2026-07-02T15:26:23.964439Z","end":"2026-07-02T15:26:24.086612Z","steps":["trace[898186283] 'read index received' (duration: 122.026495ms)","trace[898186283] 'applied index is now lower than readState.Index' (duration: 145.564µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:26:24.08673Z","caller":"traceutil/trace.go:171","msg":"trace[1866000257] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"124.789638ms","start":"2026-07-02T15:26:23.961912Z","end":"2026-07-02T15:26:24.086702Z","steps":["trace[1866000257] 'process raft request' (duration: 124.594983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:24.086865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.405145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.82\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-02T15:26:24.086943Z","caller":"traceutil/trace.go:171","msg":"trace[1157799077] range","detail":"{range_begin:/registry/masterleases/199.19.213.82; range_end:; response_count:1; response_revision:972; }","duration":"122.504098ms","start":"2026-07-02T15:26:23.964398Z","end":"2026-07-02T15:26:24.086902Z","steps":["trace[1157799077] 'agreement among raft nodes before linearized reading' (duration: 122.287412ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:30.230029Z","caller":"traceutil/trace.go:171","msg":"trace[304482500] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"346.706206ms","start":"2026-07-02T15:26:29.883302Z","end":"2026-07-02T15:26:30.230009Z","steps":["trace[304482500] 'read index received' (duration: 346.619304ms)","trace[304482500] 'applied index is now lower than readState.Index' (duration: 86.162µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:26:30.230348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.987174ms","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-07-02T15:26:30.230446Z","caller":"traceutil/trace.go:171","msg":"trace[778796915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"347.143608ms","start":"2026-07-02T15:26:29.883279Z","end":"2026-07-02T15:26:30.230423Z","steps":["trace[778796915] 'agreement among raft nodes before linearized reading' (duration: 346.83206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:30.230507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:29.88325Z","time spent":"347.242971ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T15:26:30.230976Z","caller":"traceutil/trace.go:171","msg":"trace[569128708] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"348.536534ms","start":"2026-07-02T15:26:29.882416Z","end":"2026-07-02T15:26:30.230952Z","steps":["trace[569128708] 'process raft request' (duration: 347.475346ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:30.231333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:29.882405Z","time spent":"348.798562ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:26:30.730829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619743488123842429,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T15:26:31.043869Z","caller":"traceutil/trace.go:171","msg":"trace[1470349413] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"940.860723ms","start":"2026-07-02T15:26:30.102983Z","end":"2026-07-02T15:26:31.043843Z","steps":["trace[1470349413] 'process raft request' (duration: 940.677858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:31.044025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:30.102963Z","time spent":"940.976955ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:26:31.045269Z","caller":"traceutil/trace.go:171","msg":"trace[1716969229] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1030; }","duration":"815.170551ms","start":"2026-07-02T15:26:30.230081Z","end":"2026-07-02T15:26:31.045251Z","steps":["trace[1716969229] 'read index received' (duration: 813.565258ms)","trace[1716969229] 'applied index is now lower than readState.Index' (duration: 1.604393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:26:31.045514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158025865s","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-07-02T15:26:31.045466Z","caller":"traceutil/trace.go:171","msg":"trace[2090642691] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"811.033951ms","start":"2026-07-02T15:26:30.234404Z","end":"2026-07-02T15:26:31.045438Z","steps":["trace[2090642691] 'process raft request' (duration: 810.776464ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:31.045576Z","caller":"traceutil/trace.go:171","msg":"trace[1817626322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"1.158096477s","start":"2026-07-02T15:26:29.887454Z","end":"2026-07-02T15:26:31.04555Z","steps":["trace[1817626322] 'agreement among raft nodes before linearized reading' (duration: 1.157903682s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:31.045587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160696887s","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-07-02T15:26:31.045566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15749673s","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-07-02T15:26:31.045614Z","caller":"traceutil/trace.go:171","msg":"trace[281771135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"1.160722707s","start":"2026-07-02T15:26:29.884883Z","end":"2026-07-02T15:26:31.045606Z","steps":["trace[281771135] 'agreement among raft nodes before linearized reading' (duration: 1.160657206s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:31.045618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:29.887443Z","time spent":"1.158165748s","remote":"127.0.0.1:49022","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-07-02T15:26:31.045634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:29.884863Z","time spent":"1.160766888s","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T15:26:31.04564Z","caller":"traceutil/trace.go:171","msg":"trace[2089210774] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"1.157600963s","start":"2026-07-02T15:26:29.888024Z","end":"2026-07-02T15:26:31.045625Z","steps":["trace[2089210774] 'agreement among raft nodes before linearized reading' (duration: 1.157348876s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:31.045647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:30.234381Z","time spent":"811.191254ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:26:31.045676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:29.888017Z","time spent":"1.157651534s","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T15:26:31.045519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.584238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:26:31.04571Z","caller":"traceutil/trace.go:171","msg":"trace[316553434] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:994; }","duration":"612.817514ms","start":"2026-07-02T15:26:30.432887Z","end":"2026-07-02T15:26:31.045704Z","steps":["trace[316553434] 'agreement among raft nodes before linearized reading' (duration: 612.607048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:31.045732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:30.432869Z","time spent":"612.857154ms","remote":"127.0.0.1:49196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:26:52.555852Z","caller":"traceutil/trace.go:171","msg":"trace[450070885] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"215.652441ms","start":"2026-07-02T15:26:52.340173Z","end":"2026-07-02T15:26:52.555826Z","steps":["trace[450070885] 'process raft request' (duration: 215.506448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:53.178677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.002326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:26:53.178749Z","caller":"traceutil/trace.go:171","msg":"trace[1639331597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"315.148759ms","start":"2026-07-02T15:26:52.863585Z","end":"2026-07-02T15:26:53.178734Z","steps":["trace[1639331597] 'range keys from in-memory index tree' (duration: 314.885633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:53.178786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:52.863552Z","time spent":"315.226301ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T15:26:53.337131Z","caller":"traceutil/trace.go:171","msg":"trace[1124407073] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1108; }","duration":"148.096785ms","start":"2026-07-02T15:26:53.18901Z","end":"2026-07-02T15:26:53.337107Z","steps":["trace[1124407073] 'read index received' (duration: 46.413922ms)","trace[1124407073] 'applied index is now lower than readState.Index' (duration: 101.681893ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:26:53.337278Z","caller":"traceutil/trace.go:171","msg":"trace[191398976] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"153.056575ms","start":"2026-07-02T15:26:53.184102Z","end":"2026-07-02T15:26:53.337158Z","steps":["trace[191398976] 'process raft request' (duration: 152.962283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:53.337377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.344771ms","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-07-02T15:26:53.337418Z","caller":"traceutil/trace.go:171","msg":"trace[189489129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1068; }","duration":"148.408723ms","start":"2026-07-02T15:26:53.188999Z","end":"2026-07-02T15:26:53.337407Z","steps":["trace[189489129] 'agreement among raft nodes before linearized reading' (duration: 148.244529ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:53.337601Z","caller":"traceutil/trace.go:171","msg":"trace[294251878] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"153.96643ms","start":"2026-07-02T15:26:53.183617Z","end":"2026-07-02T15:26:53.337583Z","steps":["trace[294251878] 'process raft request' (duration: 153.304852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:54.581094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619743488123842624,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T15:26:54.757249Z","caller":"traceutil/trace.go:171","msg":"trace[86098046] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"676.482271ms","start":"2026-07-02T15:26:54.08074Z","end":"2026-07-02T15:26:54.757222Z","steps":["trace[86098046] 'read index received' (duration: 676.42537ms)","trace[86098046] 'applied index is now lower than readState.Index' (duration: 55.541µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:26:54.757277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:53.967612Z","time spent":"789.658179ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-02T15:26:54.757585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.646635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:26:54.75767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.806226ms","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-07-02T15:26:54.757699Z","caller":"traceutil/trace.go:171","msg":"trace[1899680900] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1069; }","duration":"676.978744ms","start":"2026-07-02T15:26:54.080701Z","end":"2026-07-02T15:26:54.757679Z","steps":["trace[1899680900] 'agreement among raft nodes before linearized reading' (duration: 676.631745ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:54.757711Z","caller":"traceutil/trace.go:171","msg":"trace[1111512245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1069; }","duration":"196.906519ms","start":"2026-07-02T15:26:54.560792Z","end":"2026-07-02T15:26:54.757699Z","steps":["trace[1111512245] 'agreement among raft nodes before linearized reading' (duration: 196.627551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:54.757752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:54.080685Z","time spent":"677.052137ms","remote":"127.0.0.1:52938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:26:55.27433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619743488123842628,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T15:26:55.299425Z","caller":"traceutil/trace.go:171","msg":"trace[985520258] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1113; }","duration":"525.996714ms","start":"2026-07-02T15:26:54.773406Z","end":"2026-07-02T15:26:55.299403Z","steps":["trace[985520258] 'read index received' (duration: 520.444858ms)","trace[985520258] 'applied index is now lower than readState.Index' (duration: 5.550886ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:26:55.299444Z","caller":"traceutil/trace.go:171","msg":"trace[422700055] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"115.502758ms","start":"2026-07-02T15:26:55.183914Z","end":"2026-07-02T15:26:55.299417Z","steps":["trace[422700055] 'process raft request' (duration: 115.432947ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:55.299439Z","caller":"traceutil/trace.go:171","msg":"trace[788137553] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"537.628929ms","start":"2026-07-02T15:26:54.761784Z","end":"2026-07-02T15:26:55.299413Z","steps":["trace[788137553] 'process raft request' (duration: 532.048863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:55.299629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.182769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-07-02T15:26:55.299673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:54.761758Z","time spent":"537.844625ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:26:55.299714Z","caller":"traceutil/trace.go:171","msg":"trace[2029929019] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1072; }","duration":"526.300022ms","start":"2026-07-02T15:26:54.773372Z","end":"2026-07-02T15:26:55.299672Z","steps":["trace[2029929019] 'agreement among raft nodes before linearized reading' (duration: 526.155228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:55.299762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:54.773361Z","time spent":"526.389894ms","remote":"127.0.0.1:48942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-02T15:26:55.299985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.982492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:26:55.300109Z","caller":"traceutil/trace.go:171","msg":"trace[1479507541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"437.157087ms","start":"2026-07-02T15:26:54.862926Z","end":"2026-07-02T15:26:55.300083Z","steps":["trace[1479507541] 'agreement among raft nodes before linearized reading' (duration: 436.972952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:55.300139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.674679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T15:26:55.300191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:54.862906Z","time spent":"437.27244ms","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T15:26:55.30022Z","caller":"traceutil/trace.go:171","msg":"trace[591100877] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1072; }","duration":"186.771302ms","start":"2026-07-02T15:26:55.113434Z","end":"2026-07-02T15:26:55.300205Z","steps":["trace[591100877] 'agreement among raft nodes before linearized reading' (duration: 186.611698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:56.012941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.72033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:26:56.013043Z","caller":"traceutil/trace.go:171","msg":"trace[873151106] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"671.754737ms","start":"2026-07-02T15:26:55.341272Z","end":"2026-07-02T15:26:56.013027Z","steps":["trace[873151106] 'read index received' (duration: 330.632337ms)","trace[873151106] 'applied index is now lower than readState.Index' (duration: 341.12092ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:26:56.013132Z","caller":"traceutil/trace.go:171","msg":"trace[607714881] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"709.787737ms","start":"2026-07-02T15:26:55.303331Z","end":"2026-07-02T15:26:56.013119Z","steps":["trace[607714881] 'process raft request' (duration: 368.660196ms)","trace[607714881] 'compare' (duration: 340.249628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:26:56.013188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:55.303314Z","time spent":"709.845399ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:26:56.013374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.245992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:26:56.013431Z","caller":"traceutil/trace.go:171","msg":"trace[187754150] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1073; }","duration":"347.308184ms","start":"2026-07-02T15:26:55.666111Z","end":"2026-07-02T15:26:56.013419Z","steps":["trace[187754150] 'agreement among raft nodes before linearized reading' (duration: 347.15431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:56.013434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.138317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T15:26:56.013458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.991925ms","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-07-02T15:26:56.013521Z","caller":"traceutil/trace.go:171","msg":"trace[945310996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1073; }","duration":"672.278291ms","start":"2026-07-02T15:26:55.341226Z","end":"2026-07-02T15:26:56.013505Z","steps":["trace[945310996] 'agreement among raft nodes before linearized reading' (duration: 672.069475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:56.013571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:55.341208Z","time spent":"672.354352ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T15:26:56.01353Z","caller":"traceutil/trace.go:171","msg":"trace[1026468671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1073; }","duration":"601.089938ms","start":"2026-07-02T15:26:55.412424Z","end":"2026-07-02T15:26:56.013514Z","steps":["trace[1026468671] 'agreement among raft nodes before linearized reading' (duration: 600.904803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:56.013657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.217129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-02T15:26:56.013674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:55.412416Z","time spent":"601.243793ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T15:26:56.013472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:55.666057Z","time spent":"347.405567ms","remote":"127.0.0.1:49240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":95,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:26:56.014084Z","caller":"traceutil/trace.go:171","msg":"trace[995938818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1073; }","duration":"672.336083ms","start":"2026-07-02T15:26:55.341348Z","end":"2026-07-02T15:26:56.013685Z","steps":["trace[995938818] 'agreement among raft nodes before linearized reading' (duration: 671.89058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:56.014134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:55.341333Z","time spent":"672.787414ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T15:26:56.41716Z","caller":"traceutil/trace.go:171","msg":"trace[324275579] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"399.847556ms","start":"2026-07-02T15:26:56.017287Z","end":"2026-07-02T15:26:56.417135Z","steps":["trace[324275579] 'process raft request' (duration: 399.704392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:56.417315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:56.017272Z","time spent":"399.959969ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:26:56.438843Z","caller":"traceutil/trace.go:171","msg":"trace[2062357986] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"420.352745ms","start":"2026-07-02T15:26:56.018467Z","end":"2026-07-02T15:26:56.438819Z","steps":["trace[2062357986] 'process raft request' (duration: 420.311944ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:56.439115Z","caller":"traceutil/trace.go:171","msg":"trace[698230259] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"421.253739ms","start":"2026-07-02T15:26:56.017636Z","end":"2026-07-02T15:26:56.43889Z","steps":["trace[698230259] 'process raft request' (duration: 421.005342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:56.439187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:56.018444Z","time spent":"420.662993ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:26:56.439299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:56.017626Z","time spent":"421.560136ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:26:56.926217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.907451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:26:56.926574Z","caller":"traceutil/trace.go:171","msg":"trace[753849866] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"393.287563ms","start":"2026-07-02T15:26:56.533236Z","end":"2026-07-02T15:26:56.926524Z","steps":["trace[753849866] 'process raft request' (duration: 182.977983ms)","trace[753849866] 'compare' (duration: 209.736435ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:26:56.926721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:56.533209Z","time spent":"393.435687ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:26:57.427182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.925539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-02T15:26:57.427243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.966693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:26:57.427322Z","caller":"traceutil/trace.go:171","msg":"trace[1842656419] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1077; }","duration":"269.109847ms","start":"2026-07-02T15:26:57.158194Z","end":"2026-07-02T15:26:57.427304Z","steps":["trace[1842656419] 'count revisions from in-memory index tree' (duration: 268.800499ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:57.427272Z","caller":"traceutil/trace.go:171","msg":"trace[1337321464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1077; }","duration":"124.073893ms","start":"2026-07-02T15:26:57.303181Z","end":"2026-07-02T15:26:57.427255Z","steps":["trace[1337321464] 'range keys from in-memory index tree' (duration: 123.742964ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:57.427554Z","caller":"traceutil/trace.go:171","msg":"trace[1812291634] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"121.964697ms","start":"2026-07-02T15:26:57.305568Z","end":"2026-07-02T15:26:57.427533Z","steps":["trace[1812291634] 'process raft request' (duration: 117.373767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:57.833754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.293823ms","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-07-02T15:26:57.833857Z","caller":"traceutil/trace.go:171","msg":"trace[174422470] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1079; }","duration":"258.469597ms","start":"2026-07-02T15:26:57.575362Z","end":"2026-07-02T15:26:57.833832Z","steps":["trace[174422470] 'count revisions from in-memory index tree' (duration: 258.215381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:57.833983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.76195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:26:57.834056Z","caller":"traceutil/trace.go:171","msg":"trace[1202483642] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1079; }","duration":"270.874322ms","start":"2026-07-02T15:26:57.563158Z","end":"2026-07-02T15:26:57.834032Z","steps":["trace[1202483642] 'count revisions from in-memory index tree' (duration: 270.676437ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:58.760914Z","caller":"traceutil/trace.go:171","msg":"trace[639994438] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"410.557667ms","start":"2026-07-02T15:26:58.350335Z","end":"2026-07-02T15:26:58.760892Z","steps":["trace[639994438] 'read index received' (duration: 410.352562ms)","trace[639994438] 'applied index is now lower than readState.Index' (duration: 204.165µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:26:58.761081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.733172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T15:26:58.76113Z","caller":"traceutil/trace.go:171","msg":"trace[1902468940] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1080; }","duration":"410.835405ms","start":"2026-07-02T15:26:58.350278Z","end":"2026-07-02T15:26:58.761113Z","steps":["trace[1902468940] 'agreement among raft nodes before linearized reading' (duration: 410.746873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:58.761165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:58.350257Z","time spent":"410.899336ms","remote":"127.0.0.1:53174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:26:58.761118Z","caller":"traceutil/trace.go:171","msg":"trace[1169556992] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"739.737915ms","start":"2026-07-02T15:26:58.021275Z","end":"2026-07-02T15:26:58.761012Z","steps":["trace[1169556992] 'process raft request' (duration: 739.477418ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:58.761241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.543926ms","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-07-02T15:26:58.761298Z","caller":"traceutil/trace.go:171","msg":"trace[1314760275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"340.641748ms","start":"2026-07-02T15:26:58.420641Z","end":"2026-07-02T15:26:58.761283Z","steps":["trace[1314760275] 'agreement among raft nodes before linearized reading' (duration: 340.494794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:58.761336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:58.420621Z","time spent":"340.707519ms","remote":"127.0.0.1:49022","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-07-02T15:26:58.76149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:58.021247Z","time spent":"740.064933ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:26:58.761524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.230998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-02T15:26:58.761548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.613689ms","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-07-02T15:26:58.761581Z","caller":"traceutil/trace.go:171","msg":"trace[2051327776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"318.64799ms","start":"2026-07-02T15:26:58.442924Z","end":"2026-07-02T15:26:58.761572Z","steps":["trace[2051327776] 'agreement among raft nodes before linearized reading' (duration: 318.550457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:58.761608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:58.442891Z","time spent":"318.711292ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T15:26:58.761553Z","caller":"traceutil/trace.go:171","msg":"trace[1567952670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1080; }","duration":"318.29905ms","start":"2026-07-02T15:26:58.443245Z","end":"2026-07-02T15:26:58.761544Z","steps":["trace[1567952670] 'agreement among raft nodes before linearized reading' (duration: 318.204458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:26:58.761655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:26:58.443226Z","time spent":"318.420284ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T15:26:58.868557Z","caller":"traceutil/trace.go:171","msg":"trace[629715884] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"103.054451ms","start":"2026-07-02T15:26:58.765469Z","end":"2026-07-02T15:26:58.868524Z","steps":["trace[629715884] 'process raft request' (duration: 102.139646ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:26:58.868605Z","caller":"traceutil/trace.go:171","msg":"trace[888084892] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"102.988489ms","start":"2026-07-02T15:26:58.765594Z","end":"2026-07-02T15:26:58.868582Z","steps":["trace[888084892] 'process raft request' (duration: 102.871166ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:01.628777Z","caller":"traceutil/trace.go:171","msg":"trace[192972033] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"138.76358ms","start":"2026-07-02T15:27:01.489993Z","end":"2026-07-02T15:27:01.628756Z","steps":["trace[192972033] 'process raft request' (duration: 138.086993ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:12.95418Z","caller":"traceutil/trace.go:171","msg":"trace[609345693] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"131.227661ms","start":"2026-07-02T15:27:12.822908Z","end":"2026-07-02T15:27:12.954135Z","steps":["trace[609345693] 'process raft request' (duration: 130.948004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:14.559629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11619743488123842877,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T15:27:14.93989Z","caller":"traceutil/trace.go:171","msg":"trace[610173700] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"880.385875ms","start":"2026-07-02T15:27:14.059487Z","end":"2026-07-02T15:27:14.939873Z","steps":["trace[610173700] 'read index received' (duration: 880.298733ms)","trace[610173700] 'applied index is now lower than readState.Index' (duration: 86.322µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:27:14.939949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:13.968912Z","time spent":"971.029619ms","remote":"127.0.0.1:48808","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-02T15:27:14.940069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.541029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:27:14.940155Z","caller":"traceutil/trace.go:171","msg":"trace[327003784] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1182; }","duration":"880.651342ms","start":"2026-07-02T15:27:14.059478Z","end":"2026-07-02T15:27:14.940129Z","steps":["trace[327003784] 'agreement among raft nodes before linearized reading' (duration: 880.483198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:14.940228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:14.059424Z","time spent":"880.787585ms","remote":"127.0.0.1:52966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-02T15:27:32.223568Z","caller":"traceutil/trace.go:171","msg":"trace[1800878692] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"379.08848ms","start":"2026-07-02T15:27:31.844434Z","end":"2026-07-02T15:27:32.223523Z","steps":["trace[1800878692] 'process raft request' (duration: 378.872674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:32.223786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:31.844423Z","time spent":"379.251184ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:27:33.227014Z","caller":"traceutil/trace.go:171","msg":"trace[189258433] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"169.554328ms","start":"2026-07-02T15:27:33.057432Z","end":"2026-07-02T15:27:33.226986Z","steps":["trace[189258433] 'read index received' (duration: 169.412935ms)","trace[189258433] 'applied index is now lower than readState.Index' (duration: 140.293µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:27:33.227062Z","caller":"traceutil/trace.go:171","msg":"trace[642848417] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"182.703545ms","start":"2026-07-02T15:27:33.044337Z","end":"2026-07-02T15:27:33.227041Z","steps":["trace[642848417] 'process raft request' (duration: 182.485429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:33.22723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.773814ms","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-07-02T15:27:33.227313Z","caller":"traceutil/trace.go:171","msg":"trace[1892612112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1263; }","duration":"169.903768ms","start":"2026-07-02T15:27:33.057389Z","end":"2026-07-02T15:27:33.227293Z","steps":["trace[1892612112] 'agreement among raft nodes before linearized reading' (duration: 169.680812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:33.227311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.805884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-02T15:27:33.227339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.262787ms","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-07-02T15:27:33.227376Z","caller":"traceutil/trace.go:171","msg":"trace[432244621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1263; }","duration":"164.924207ms","start":"2026-07-02T15:27:33.062432Z","end":"2026-07-02T15:27:33.227356Z","steps":["trace[432244621] 'agreement among raft nodes before linearized reading' (duration: 164.733951ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:33.227399Z","caller":"traceutil/trace.go:171","msg":"trace[714527430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1263; }","duration":"162.34882ms","start":"2026-07-02T15:27:33.065035Z","end":"2026-07-02T15:27:33.227383Z","steps":["trace[714527430] 'agreement among raft nodes before linearized reading' (duration: 162.219296ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:33.358976Z","caller":"traceutil/trace.go:171","msg":"trace[1740863865] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"124.150786ms","start":"2026-07-02T15:27:33.234801Z","end":"2026-07-02T15:27:33.358952Z","steps":["trace[1740863865] 'process raft request' (duration: 124.002331ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:33.373907Z","caller":"traceutil/trace.go:171","msg":"trace[1850811001] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"139.025157ms","start":"2026-07-02T15:27:33.234865Z","end":"2026-07-02T15:27:33.37389Z","steps":["trace[1850811001] 'process raft request' (duration: 139.000216ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:33.373962Z","caller":"traceutil/trace.go:171","msg":"trace[1645600794] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"139.120789ms","start":"2026-07-02T15:27:33.2348Z","end":"2026-07-02T15:27:33.373921Z","steps":["trace[1645600794] 'process raft request' (duration: 138.968906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:34.542266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.436211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:27:34.542538Z","caller":"traceutil/trace.go:171","msg":"trace[43907934] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"527.222505ms","start":"2026-07-02T15:27:34.015296Z","end":"2026-07-02T15:27:34.542519Z","steps":["trace[43907934] 'process raft request' (duration: 527.129623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:34.542625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.015279Z","time spent":"527.305838ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T15:27:34.542775Z","caller":"traceutil/trace.go:171","msg":"trace[77834156] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"529.998399ms","start":"2026-07-02T15:27:34.012723Z","end":"2026-07-02T15:27:34.542721Z","steps":["trace[77834156] 'read index received' (duration: 26.068055ms)","trace[77834156] 'applied index is now lower than readState.Index' (duration: 503.923063ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:27:34.543004Z","caller":"traceutil/trace.go:171","msg":"trace[936312092] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"543.099883ms","start":"2026-07-02T15:27:33.999653Z","end":"2026-07-02T15:27:34.542753Z","steps":["trace[936312092] 'process raft request' (duration: 39.095598ms)","trace[936312092] 'compare' (duration: 502.800884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:27:34.543072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.338388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-02T15:27:34.543135Z","caller":"traceutil/trace.go:171","msg":"trace[291555655] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1272; }","duration":"530.451511ms","start":"2026-07-02T15:27:34.01267Z","end":"2026-07-02T15:27:34.543121Z","steps":["trace[291555655] 'agreement among raft nodes before linearized reading' (duration: 530.272186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:34.543168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.012648Z","time spent":"530.513332ms","remote":"127.0.0.1:48942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-02T15:27:34.543217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:33.999638Z","time spent":"543.460793ms","remote":"127.0.0.1:48834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T15:27:34.543684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.506456ms","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-07-02T15:27:34.543754Z","caller":"traceutil/trace.go:171","msg":"trace[1478075560] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1272; }","duration":"510.600009ms","start":"2026-07-02T15:27:34.033141Z","end":"2026-07-02T15:27:34.543741Z","steps":["trace[1478075560] 'agreement among raft nodes before linearized reading' (duration: 510.495796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:34.543808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.033134Z","time spent":"510.66658ms","remote":"127.0.0.1:48984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:27:34.544429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.445363ms","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-07-02T15:27:34.544603Z","caller":"traceutil/trace.go:171","msg":"trace[2105632166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"316.573416ms","start":"2026-07-02T15:27:34.227906Z","end":"2026-07-02T15:27:34.544479Z","steps":["trace[2105632166] 'agreement among raft nodes before linearized reading' (duration: 315.747164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:34.5448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.227886Z","time spent":"316.896844ms","remote":"127.0.0.1:49022","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-07-02T15:27:35.805217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.196488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:27:35.805363Z","caller":"traceutil/trace.go:171","msg":"trace[1728703420] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"1.257561493s","start":"2026-07-02T15:27:34.547771Z","end":"2026-07-02T15:27:35.805332Z","steps":["trace[1728703420] 'process raft request' (duration: 439.16178ms)","trace[1728703420] 'compare' (duration: 817.88983ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:27:35.805438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.54774Z","time spent":"1.257666636s","remote":"127.0.0.1:48834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T15:27:36.005438Z","caller":"traceutil/trace.go:171","msg":"trace[1574814393] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1325; }","duration":"1.141137992s","start":"2026-07-02T15:27:34.864282Z","end":"2026-07-02T15:27:36.00542Z","steps":["trace[1574814393] 'read index received' (duration: 122.619135ms)","trace[1574814393] 'applied index is now lower than readState.Index' (duration: 1.018518106s)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:27:36.005537Z","caller":"traceutil/trace.go:171","msg":"trace[1117225409] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.456773633s","start":"2026-07-02T15:27:34.548754Z","end":"2026-07-02T15:27:36.005528Z","steps":["trace[1117225409] 'process raft request' (duration: 1.456592629s)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:36.005543Z","caller":"traceutil/trace.go:171","msg":"trace[133479632] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.457389469s","start":"2026-07-02T15:27:34.548121Z","end":"2026-07-02T15:27:36.00551Z","steps":["trace[133479632] 'process raft request' (duration: 1.398561822s)","trace[133479632] 'compare' (duration: 58.56235ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:27:36.005616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.548735Z","time spent":"1.456823034s","remote":"127.0.0.1:48834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T15:27:36.005673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.54811Z","time spent":"1.457492642s","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:27:36.005865Z","caller":"traceutil/trace.go:171","msg":"trace[924056278] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.453442175s","start":"2026-07-02T15:27:34.552415Z","end":"2026-07-02T15:27:36.005857Z","steps":["trace[924056278] 'process raft request' (duration: 1.452973793s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:36.005915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.552405Z","time spent":"1.453481126s","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:27:36.005982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141700156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:27:36.006003Z","caller":"traceutil/trace.go:171","msg":"trace[855468868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"1.141751118s","start":"2026-07-02T15:27:34.864246Z","end":"2026-07-02T15:27:36.005997Z","steps":["trace[855468868] 'agreement among raft nodes before linearized reading' (duration: 1.141715587s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:36.006028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.864228Z","time spent":"1.141795339s","remote":"127.0.0.1:48772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T15:27:36.021578Z","caller":"traceutil/trace.go:171","msg":"trace[801005703] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"374.814368ms","start":"2026-07-02T15:27:35.646744Z","end":"2026-07-02T15:27:36.021558Z","steps":["trace[801005703] 'process raft request' (duration: 374.48918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:36.021693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:35.646717Z","time spent":"374.91593ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:27:36.021898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.913998ms","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-07-02T15:27:36.021918Z","caller":"traceutil/trace.go:171","msg":"trace[1904777165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"642.97556ms","start":"2026-07-02T15:27:35.378936Z","end":"2026-07-02T15:27:36.021912Z","steps":["trace[1904777165] 'agreement among raft nodes before linearized reading' (duration: 642.891148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:36.021937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:35.378928Z","time spent":"643.005311ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T15:27:36.022047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.315096ms","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-07-02T15:27:36.022062Z","caller":"traceutil/trace.go:171","msg":"trace[419714051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"644.374537ms","start":"2026-07-02T15:27:35.377683Z","end":"2026-07-02T15:27:36.022057Z","steps":["trace[419714051] 'agreement among raft nodes before linearized reading' (duration: 644.323536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:36.022076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:35.37766Z","time spent":"644.412039ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T15:27:36.022184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.668279ms","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-07-02T15:27:36.022202Z","caller":"traceutil/trace.go:171","msg":"trace[374365329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"659.769942ms","start":"2026-07-02T15:27:35.362426Z","end":"2026-07-02T15:27:36.022196Z","steps":["trace[374365329] 'agreement among raft nodes before linearized reading' (duration: 659.720631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:36.022256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:35.362398Z","time spent":"659.851374ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T15:27:36.022418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.397037ms","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-07-02T15:27:36.022463Z","caller":"traceutil/trace.go:171","msg":"trace[498116821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1277; }","duration":"790.480149ms","start":"2026-07-02T15:27:35.23197Z","end":"2026-07-02T15:27:36.02245Z","steps":["trace[498116821] 'agreement among raft nodes before linearized reading' (duration: 790.337976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:36.022494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:35.231949Z","time spent":"790.538671ms","remote":"127.0.0.1:49022","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":"warn","ts":"2026-07-02T15:27:36.022584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048164287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:27:36.022608Z","caller":"traceutil/trace.go:171","msg":"trace[36617508] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1277; }","duration":"1.048218958s","start":"2026-07-02T15:27:34.974381Z","end":"2026-07-02T15:27:36.0226Z","steps":["trace[36617508] 'agreement among raft nodes before linearized reading' (duration: 1.048176437s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:36.022625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:34.974329Z","time spent":"1.04829086s","remote":"127.0.0.1:53120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:27:36.022728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.503206ms","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-07-02T15:27:36.022745Z","caller":"traceutil/trace.go:171","msg":"trace[442240685] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1277; }","duration":"799.567448ms","start":"2026-07-02T15:27:35.223171Z","end":"2026-07-02T15:27:36.022738Z","steps":["trace[442240685] 'agreement among raft nodes before linearized reading' (duration: 799.538117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:36.022762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:35.223146Z","time spent":"799.612079ms","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-02T15:27:44.665445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.946127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:27:44.665533Z","caller":"traceutil/trace.go:171","msg":"trace[2079676395] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"537.498309ms","start":"2026-07-02T15:27:44.128017Z","end":"2026-07-02T15:27:44.665516Z","steps":["trace[2079676395] 'read index received' (duration: 14.412739ms)","trace[2079676395] 'applied index is now lower than readState.Index' (duration: 523.08465ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:27:44.66558Z","caller":"traceutil/trace.go:171","msg":"trace[654637762] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"581.246031ms","start":"2026-07-02T15:27:44.084305Z","end":"2026-07-02T15:27:44.665551Z","steps":["trace[654637762] 'process raft request' (duration: 58.11137ms)","trace[654637762] 'compare' (duration: 522.847414ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:27:44.665706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:44.08428Z","time spent":"581.357364ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:27:44.665716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.679524ms","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-07-02T15:27:44.665749Z","caller":"traceutil/trace.go:171","msg":"trace[1138733469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"537.779987ms","start":"2026-07-02T15:27:44.12796Z","end":"2026-07-02T15:27:44.66574Z","steps":["trace[1138733469] 'agreement among raft nodes before linearized reading' (duration: 537.646283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:44.665778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:44.127946Z","time spent":"537.824738ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T15:27:44.66589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.297928ms","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-07-02T15:27:44.665961Z","caller":"traceutil/trace.go:171","msg":"trace[1447905736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"527.426472ms","start":"2026-07-02T15:27:44.138516Z","end":"2026-07-02T15:27:44.665942Z","steps":["trace[1447905736] 'agreement among raft nodes before linearized reading' (duration: 527.146555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:44.666032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:44.138506Z","time spent":"527.515544ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T15:27:44.66618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.576595ms","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-07-02T15:27:44.666213Z","caller":"traceutil/trace.go:171","msg":"trace[2039018053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"527.609236ms","start":"2026-07-02T15:27:44.138596Z","end":"2026-07-02T15:27:44.666205Z","steps":["trace[2039018053] 'agreement among raft nodes before linearized reading' (duration: 527.489923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:44.666235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:27:44.138591Z","time spent":"527.639776ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T15:27:44.937065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.261733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T15:27:44.937316Z","caller":"traceutil/trace.go:171","msg":"trace[1730525934] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"268.101863ms","start":"2026-07-02T15:27:44.669175Z","end":"2026-07-02T15:27:44.937277Z","steps":["trace[1730525934] 'process raft request' (duration: 106.527261ms)","trace[1730525934] 'compare' (duration: 161.079809ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:27:44.937367Z","caller":"traceutil/trace.go:171","msg":"trace[1851503129] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1395; }","duration":"221.730835ms","start":"2026-07-02T15:27:44.715615Z","end":"2026-07-02T15:27:44.937346Z","steps":["trace[1851503129] 'read index received' (duration: 60.102521ms)","trace[1851503129] 'applied index is now lower than readState.Index' (duration: 161.627044ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:27:44.937482Z","caller":"traceutil/trace.go:171","msg":"trace[277217922] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"266.738229ms","start":"2026-07-02T15:27:44.670728Z","end":"2026-07-02T15:27:44.937467Z","steps":["trace[277217922] 'process raft request' (duration: 266.547324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:44.937656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.424999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:27:44.937878Z","caller":"traceutil/trace.go:171","msg":"trace[820293829] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1343; }","duration":"177.719766ms","start":"2026-07-02T15:27:44.760152Z","end":"2026-07-02T15:27:44.937872Z","steps":["trace[820293829] 'agreement among raft nodes before linearized reading' (duration: 177.405509ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:44.93747Z","caller":"traceutil/trace.go:171","msg":"trace[1670950492] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"267.19224ms","start":"2026-07-02T15:27:44.67024Z","end":"2026-07-02T15:27:44.937432Z","steps":["trace[1670950492] 'process raft request' (duration: 266.939244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:44.93801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.384612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-02T15:27:44.938054Z","caller":"traceutil/trace.go:171","msg":"trace[727610330] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1343; }","duration":"222.476495ms","start":"2026-07-02T15:27:44.715564Z","end":"2026-07-02T15:27:44.93804Z","steps":["trace[727610330] 'agreement among raft nodes before linearized reading' (duration: 222.327381ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:53.20576Z","caller":"traceutil/trace.go:171","msg":"trace[519974725] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1433; }","duration":"211.83402ms","start":"2026-07-02T15:27:52.993901Z","end":"2026-07-02T15:27:53.205735Z","steps":["trace[519974725] 'read index received' (duration: 56.96096ms)","trace[519974725] 'applied index is now lower than readState.Index' (duration: 154.87199ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:27:53.205831Z","caller":"traceutil/trace.go:171","msg":"trace[1415239691] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"230.642193ms","start":"2026-07-02T15:27:52.975162Z","end":"2026-07-02T15:27:53.205804Z","steps":["trace[1415239691] 'process raft request' (duration: 230.364386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:53.205979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.048915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-02T15:27:53.20602Z","caller":"traceutil/trace.go:171","msg":"trace[187012171] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1380; }","duration":"212.176778ms","start":"2026-07-02T15:27:52.993832Z","end":"2026-07-02T15:27:53.206009Z","steps":["trace[187012171] 'agreement among raft nodes before linearized reading' (duration: 212.004434ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:53.205864Z","caller":"traceutil/trace.go:171","msg":"trace[370051003] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"230.626663ms","start":"2026-07-02T15:27:52.975203Z","end":"2026-07-02T15:27:53.20583Z","steps":["trace[370051003] 'process raft request' (duration: 230.482489ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:53.317292Z","caller":"traceutil/trace.go:171","msg":"trace[557947972] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"103.213016ms","start":"2026-07-02T15:27:53.214052Z","end":"2026-07-02T15:27:53.317265Z","steps":["trace[557947972] 'read index received' (duration: 102.57938ms)","trace[557947972] 'applied index is now lower than readState.Index' (duration: 632.396µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:27:53.317382Z","caller":"traceutil/trace.go:171","msg":"trace[96768248] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"107.68808ms","start":"2026-07-02T15:27:53.20967Z","end":"2026-07-02T15:27:53.317359Z","steps":["trace[96768248] 'process raft request' (duration: 107.084375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:27:53.31743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.35631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:27:53.317468Z","caller":"traceutil/trace.go:171","msg":"trace[921660979] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1381; }","duration":"103.460183ms","start":"2026-07-02T15:27:53.213997Z","end":"2026-07-02T15:27:53.317457Z","steps":["trace[921660979] 'agreement among raft nodes before linearized reading' (duration: 103.36963ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:54.318458Z","caller":"traceutil/trace.go:171","msg":"trace[119611872] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"135.218507ms","start":"2026-07-02T15:27:54.183208Z","end":"2026-07-02T15:27:54.318427Z","steps":["trace[119611872] 'process raft request' (duration: 110.767251ms)","trace[119611872] 'compare' (duration: 24.194309ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:27:55.565121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.716477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-02T15:27:55.565204Z","caller":"traceutil/trace.go:171","msg":"trace[1128043850] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1389; }","duration":"241.84641ms","start":"2026-07-02T15:27:55.323333Z","end":"2026-07-02T15:27:55.565179Z","steps":["trace[1128043850] 'range keys from in-memory index tree' (duration: 241.541442ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:27:56.067146Z","caller":"traceutil/trace.go:171","msg":"trace[1175673789] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"113.374787ms","start":"2026-07-02T15:27:55.95375Z","end":"2026-07-02T15:27:56.067125Z","steps":["trace[1175673789] 'process raft request' (duration: 112.978146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:28:17.46333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.521902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-02T15:28:17.463333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.938539ms","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-07-02T15:28:17.463416Z","caller":"traceutil/trace.go:171","msg":"trace[1154979096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1489; }","duration":"115.664925ms","start":"2026-07-02T15:28:17.347735Z","end":"2026-07-02T15:28:17.4634Z","steps":["trace[1154979096] 'range keys from in-memory index tree' (duration: 115.343257ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:28:17.46346Z","caller":"traceutil/trace.go:171","msg":"trace[1039776793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1489; }","duration":"112.136134ms","start":"2026-07-02T15:28:17.351303Z","end":"2026-07-02T15:28:17.463439Z","steps":["trace[1039776793] 'range keys from in-memory index tree' (duration: 111.682173ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:28:29.685456Z","caller":"traceutil/trace.go:171","msg":"trace[1536493186] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"143.910729ms","start":"2026-07-02T15:28:29.541511Z","end":"2026-07-02T15:28:29.685422Z","steps":["trace[1536493186] 'process raft request' (duration: 97.987252ms)","trace[1536493186] 'compare' (duration: 45.817694ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:28:52.981795Z","caller":"traceutil/trace.go:171","msg":"trace[35406064] linearizableReadLoop","detail":"{readStateIndex:1721; appliedIndex:1720; }","duration":"117.189056ms","start":"2026-07-02T15:28:52.86458Z","end":"2026-07-02T15:28:52.981769Z","steps":["trace[35406064] 'read index received' (duration: 116.97186ms)","trace[35406064] 'applied index is now lower than readState.Index' (duration: 215.446µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:28:52.982027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.423472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:28:52.982083Z","caller":"traceutil/trace.go:171","msg":"trace[462650892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1653; }","duration":"117.547044ms","start":"2026-07-02T15:28:52.864519Z","end":"2026-07-02T15:28:52.982066Z","steps":["trace[462650892] 'agreement among raft nodes before linearized reading' (duration: 117.396321ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:28:52.982488Z","caller":"traceutil/trace.go:171","msg":"trace[1389398265] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"155.107938ms","start":"2026-07-02T15:28:52.827363Z","end":"2026-07-02T15:28:52.982471Z","steps":["trace[1389398265] 'process raft request' (duration: 154.261816ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:29:22.819133Z","caller":"traceutil/trace.go:171","msg":"trace[99569424] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"144.28857ms","start":"2026-07-02T15:29:22.674815Z","end":"2026-07-02T15:29:22.819104Z","steps":["trace[99569424] 'process raft request' (duration: 143.927541ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:30:46.59479Z","caller":"traceutil/trace.go:171","msg":"trace[499563776] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2262; }","duration":"268.696101ms","start":"2026-07-02T15:30:46.326065Z","end":"2026-07-02T15:30:46.594762Z","steps":["trace[499563776] 'read index received' (duration: 233.807159ms)","trace[499563776] 'applied index is now lower than readState.Index' (duration: 34.887882ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:30:46.594924Z","caller":"traceutil/trace.go:171","msg":"trace[2144847419] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"284.442214ms","start":"2026-07-02T15:30:46.310466Z","end":"2026-07-02T15:30:46.594908Z","steps":["trace[2144847419] 'process raft request' (duration: 249.379648ms)","trace[2144847419] 'compare' (duration: 34.793999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T15:30:46.59539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.267762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-02T15:30:46.595489Z","caller":"traceutil/trace.go:171","msg":"trace[1366023683] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2172; }","duration":"114.426467ms","start":"2026-07-02T15:30:46.48104Z","end":"2026-07-02T15:30:46.595467Z","steps":["trace[1366023683] 'agreement among raft nodes before linearized reading' (duration: 114.265492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:30:46.595494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.409931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T15:30:46.59565Z","caller":"traceutil/trace.go:171","msg":"trace[124110042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2172; }","duration":"269.601555ms","start":"2026-07-02T15:30:46.326038Z","end":"2026-07-02T15:30:46.59564Z","steps":["trace[124110042] 'agreement among raft nodes before linearized reading' (duration: 269.346409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:30:46.595582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.120901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-02T15:30:46.595781Z","caller":"traceutil/trace.go:171","msg":"trace[396270197] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2172; }","duration":"136.331577ms","start":"2026-07-02T15:30:46.459412Z","end":"2026-07-02T15:30:46.595744Z","steps":["trace[396270197] 'agreement among raft nodes before linearized reading' (duration: 135.644438ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:30:46.974987Z","caller":"traceutil/trace.go:171","msg":"trace[245942579] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"376.056137ms","start":"2026-07-02T15:30:46.598905Z","end":"2026-07-02T15:30:46.974961Z","steps":["trace[245942579] 'process raft request' (duration: 375.105883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:30:46.975225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:30:46.598896Z","time spent":"376.17697ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T15:30:46.975353Z","caller":"traceutil/trace.go:171","msg":"trace[1700949935] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2264; }","duration":"111.314436ms","start":"2026-07-02T15:30:46.864019Z","end":"2026-07-02T15:30:46.975333Z","steps":["trace[1700949935] 'read index received' (duration: 109.86221ms)","trace[1700949935] 'applied index is now lower than readState.Index' (duration: 1.449366ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T15:30:46.974985Z","caller":"traceutil/trace.go:171","msg":"trace[1828635726] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"375.887882ms","start":"2026-07-02T15:30:46.59907Z","end":"2026-07-02T15:30:46.974958Z","steps":["trace[1828635726] 'process raft request' (duration: 375.779639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:30:46.975519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.490671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T15:30:46.975638Z","caller":"traceutil/trace.go:171","msg":"trace[535728858] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"374.99918ms","start":"2026-07-02T15:30:46.600607Z","end":"2026-07-02T15:30:46.975606Z","steps":["trace[535728858] 'process raft request' (duration: 374.304513ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T15:30:46.97565Z","caller":"traceutil/trace.go:171","msg":"trace[1454675750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2176; }","duration":"111.619494ms","start":"2026-07-02T15:30:46.863979Z","end":"2026-07-02T15:30:46.975598Z","steps":["trace[1454675750] 'agreement among raft nodes before linearized reading' (duration: 111.46021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T15:30:46.975824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:30:46.600579Z","time spent":"375.152204ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T15:30:46.975616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T15:30:46.599061Z","time spent":"376.425996ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}