{"level":"warn","ts":"2026-07-14T17:00:25.630152Z","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-14T17:00:25.630254Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.229: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.229:2380","--initial-cluster=instance=https://199.19.213.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.229: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-14T17:00:25.630335Z","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-14T17:00:25.630347Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-07-14T17:00:25.630366Z","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-14T17:00:25.631123Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"]} {"level":"info","ts":"2026-07-14T17:00:25.631213Z","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.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.229: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-14T17:00:25.634098Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.601037ms"} {"level":"info","ts":"2026-07-14T17:00:25.641107Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"98aaa0557ab926b1","cluster-id":"250cc9bd258bffbf"} {"level":"info","ts":"2026-07-14T17:00:25.641169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T17:00:25.641187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 0"} {"level":"info","ts":"2026-07-14T17:00:25.641201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 98aaa0557ab926b1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T17:00:25.641213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became follower at term 1"} {"level":"info","ts":"2026-07-14T17:00:25.641251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"warn","ts":"2026-07-14T17:00:25.64555Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T17:00:25.646696Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T17:00:25.647686Z","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-14T17:00:25.650484Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"98aaa0557ab926b1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T17:00:25.651853Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"98aaa0557ab926b1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T17:00:25.651945Z","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-14T17:00:25.651973Z","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-14T17:00:25.651982Z","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-14T17:00:25.653236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 switched to configuration voters=(11000781328797476529)"} {"level":"info","ts":"2026-07-14T17:00:25.653949Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","added-peer-id":"98aaa0557ab926b1","added-peer-peer-urls":["https://199.19.213.229:2380"]} {"level":"info","ts":"2026-07-14T17:00:25.654799Z","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-14T17:00:25.654991Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"98aaa0557ab926b1","initial-advertise-peer-urls":["https://199.19.213.229:2380"],"listen-peer-urls":["https://199.19.213.229:2380"],"advertise-client-urls":["https://199.19.213.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T17:00:25.655017Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T17:00:25.655124Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-07-14T17:00:25.655138Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.229:2380"} {"level":"info","ts":"2026-07-14T17:00:26.242061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T17:00:26.242116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T17:00:26.242157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgPreVoteResp from 98aaa0557ab926b1 at term 1"} {"level":"info","ts":"2026-07-14T17:00:26.242176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became candidate at term 2"} {"level":"info","ts":"2026-07-14T17:00:26.242187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 received MsgVoteResp from 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-07-14T17:00:26.242208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"98aaa0557ab926b1 became leader at term 2"} {"level":"info","ts":"2026-07-14T17:00:26.242234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 98aaa0557ab926b1 elected leader 98aaa0557ab926b1 at term 2"} {"level":"info","ts":"2026-07-14T17:00:26.2434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"98aaa0557ab926b1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.229:2379]}","request-path":"/0/members/98aaa0557ab926b1/attributes","cluster-id":"250cc9bd258bffbf","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T17:00:26.243584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:00:26.243668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:00:26.243923Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:00:26.244393Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T17:00:26.244447Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T17:00:26.246609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.229:2379"} {"level":"info","ts":"2026-07-14T17:00:26.247335Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"250cc9bd258bffbf","local-member-id":"98aaa0557ab926b1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:00:26.247455Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:00:26.247488Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:00:26.247501Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-14T17:00:44.987192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.992449ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-14T17:00:44.987401Z","caller":"traceutil/trace.go:171","msg":"trace[1313492247] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"284.643767ms","start":"2026-07-14T17:00:44.702744Z","end":"2026-07-14T17:00:44.987388Z","steps":["trace[1313492247] 'read index received' (duration: 45.304858ms)","trace[1313492247] 'applied index is now lower than readState.Index' (duration: 239.337729ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:00:44.987582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.791721ms","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-14T17:00:44.987672Z","caller":"traceutil/trace.go:171","msg":"trace[67832052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:360; }","duration":"284.962115ms","start":"2026-07-14T17:00:44.70269Z","end":"2026-07-14T17:00:44.987652Z","steps":["trace[67832052] 'agreement among raft nodes before linearized reading' (duration: 284.75737ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:45.187663Z","caller":"traceutil/trace.go:171","msg":"trace[2066303444] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"195.526949ms","start":"2026-07-14T17:00:44.992115Z","end":"2026-07-14T17:00:45.187642Z","steps":["trace[2066303444] 'process raft request' (duration: 195.388235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:45.912015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.848264ms","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-14T17:00:45.912108Z","caller":"traceutil/trace.go:171","msg":"trace[1763834617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:361; }","duration":"228.002599ms","start":"2026-07-14T17:00:45.684091Z","end":"2026-07-14T17:00:45.912094Z","steps":["trace[1763834617] 'range keys from in-memory index tree' (duration: 227.737922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:45.912302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.463371ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:00:45.912339Z","caller":"traceutil/trace.go:171","msg":"trace[192828245] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:361; }","duration":"618.503052ms","start":"2026-07-14T17:00:45.293831Z","end":"2026-07-14T17:00:45.912334Z","steps":["trace[192828245] 'range keys from in-memory index tree' (duration: 618.458181ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:46.046367Z","caller":"traceutil/trace.go:171","msg":"trace[1396954122] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"131.396138ms","start":"2026-07-14T17:00:45.914948Z","end":"2026-07-14T17:00:46.046344Z","steps":["trace[1396954122] 'process raft request' (duration: 131.261575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:01.762484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184885739302163,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:01:01.848453Z","caller":"traceutil/trace.go:171","msg":"trace[1768822178] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"656.57757ms","start":"2026-07-14T17:01:01.191847Z","end":"2026-07-14T17:01:01.848424Z","steps":["trace[1768822178] 'process raft request' (duration: 652.184703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:01.849018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:01.191831Z","time spent":"656.707053ms","remote":"127.0.0.1:34524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:01:01.849555Z","caller":"traceutil/trace.go:171","msg":"trace[1731071554] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"637.659967ms","start":"2026-07-14T17:01:01.211871Z","end":"2026-07-14T17:01:01.849531Z","steps":["trace[1731071554] 'process raft request' (duration: 637.435152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:01.849659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.291215ms","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-14T17:01:01.84967Z","caller":"traceutil/trace.go:171","msg":"trace[728508675] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"239.856709ms","start":"2026-07-14T17:01:01.609793Z","end":"2026-07-14T17:01:01.84965Z","steps":["trace[728508675] 'process raft request' (duration: 239.680955ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:01.849707Z","caller":"traceutil/trace.go:171","msg":"trace[1264283879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:504; }","duration":"588.378457ms","start":"2026-07-14T17:01:01.261319Z","end":"2026-07-14T17:01:01.849697Z","steps":["trace[1264283879] 'agreement among raft nodes before linearized reading' (duration: 588.282615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:01.849746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:01.261291Z","time spent":"588.446549ms","remote":"127.0.0.1:34762","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-14T17:01:01.849802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:01.211862Z","time spent":"637.79191ms","remote":"127.0.0.1:34524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:01:01.849884Z","caller":"traceutil/trace.go:171","msg":"trace[1838882041] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"635.04368ms","start":"2026-07-14T17:01:01.214829Z","end":"2026-07-14T17:01:01.849873Z","steps":["trace[1838882041] 'process raft request' (duration: 634.588817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:01.849973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:01.214823Z","time spent":"635.098292ms","remote":"127.0.0.1:34524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:01:01.849983Z","caller":"traceutil/trace.go:171","msg":"trace[299459436] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"221.711842ms","start":"2026-07-14T17:01:01.628256Z","end":"2026-07-14T17:01:01.849968Z","steps":["trace[299459436] 'process raft request' (duration: 221.25975ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:01.84957Z","caller":"traceutil/trace.go:171","msg":"trace[1345234898] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:516; }","duration":"588.152232ms","start":"2026-07-14T17:01:01.2614Z","end":"2026-07-14T17:01:01.849553Z","steps":["trace[1345234898] 'read index received' (duration: 582.718348ms)","trace[1345234898] 'applied index is now lower than readState.Index' (duration: 5.432984ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:02.914749Z","caller":"traceutil/trace.go:171","msg":"trace[150564572] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"105.907241ms","start":"2026-07-14T17:01:02.808813Z","end":"2026-07-14T17:01:02.91472Z","steps":["trace[150564572] 'read index received' (duration: 105.586062ms)","trace[150564572] 'applied index is now lower than readState.Index' (duration: 320.149µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:02.914863Z","caller":"traceutil/trace.go:171","msg":"trace[1216896689] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"128.622799ms","start":"2026-07-14T17:01:02.786206Z","end":"2026-07-14T17:01:02.914828Z","steps":["trace[1216896689] 'process raft request' (duration: 128.215508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:02.914997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.156117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-07-14T17:01:02.915043Z","caller":"traceutil/trace.go:171","msg":"trace[1245318736] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:541; }","duration":"106.23995ms","start":"2026-07-14T17:01:02.80879Z","end":"2026-07-14T17:01:02.91503Z","steps":["trace[1245318736] 'agreement among raft nodes before linearized reading' (duration: 106.027394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:05.629342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.025842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:05.629424Z","caller":"traceutil/trace.go:171","msg":"trace[274930486] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:554; }","duration":"213.150286ms","start":"2026-07-14T17:01:05.416256Z","end":"2026-07-14T17:01:05.629406Z","steps":["trace[274930486] 'count revisions from in-memory index tree' (duration: 212.945641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:05.629757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.097929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:05.629812Z","caller":"traceutil/trace.go:171","msg":"trace[600649089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"171.185781ms","start":"2026-07-14T17:01:05.458615Z","end":"2026-07-14T17:01:05.629801Z","steps":["trace[600649089] 'range keys from in-memory index tree' (duration: 170.964005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:05.629911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.540486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:01:05.629963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.939611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:05.630027Z","caller":"traceutil/trace.go:171","msg":"trace[1495270478] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:554; }","duration":"213.701371ms","start":"2026-07-14T17:01:05.416305Z","end":"2026-07-14T17:01:05.630006Z","steps":["trace[1495270478] 'range keys from in-memory index tree' (duration: 213.394592ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:05.630117Z","caller":"traceutil/trace.go:171","msg":"trace[189207979] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:554; }","duration":"213.092364ms","start":"2026-07-14T17:01:05.41698Z","end":"2026-07-14T17:01:05.630072Z","steps":["trace[189207979] 'range keys from in-memory index tree' (duration: 212.870899ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:08.09748Z","caller":"traceutil/trace.go:171","msg":"trace[13741650] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"216.196366ms","start":"2026-07-14T17:01:07.881247Z","end":"2026-07-14T17:01:08.097443Z","steps":["trace[13741650] 'process raft request' (duration: 120.374101ms)","trace[13741650] 'compare' (duration: 95.700921ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:08.279627Z","caller":"traceutil/trace.go:171","msg":"trace[1337734932] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"134.158635ms","start":"2026-07-14T17:01:08.145379Z","end":"2026-07-14T17:01:08.279538Z","steps":["trace[1337734932] 'read index received' (duration: 132.48337ms)","trace[1337734932] 'applied index is now lower than readState.Index' (duration: 1.673695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:08.280116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.649818ms","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-14T17:01:08.280182Z","caller":"traceutil/trace.go:171","msg":"trace[889956857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"134.790572ms","start":"2026-07-14T17:01:08.145365Z","end":"2026-07-14T17:01:08.280155Z","steps":["trace[889956857] 'agreement among raft nodes before linearized reading' (duration: 134.34361ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:08.280365Z","caller":"traceutil/trace.go:171","msg":"trace[860547273] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"158.399294ms","start":"2026-07-14T17:01:08.121917Z","end":"2026-07-14T17:01:08.280317Z","steps":["trace[860547273] 'process raft request' (duration: 155.843866ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:08.40033Z","caller":"traceutil/trace.go:171","msg":"trace[2041107124] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"231.373126ms","start":"2026-07-14T17:01:08.168929Z","end":"2026-07-14T17:01:08.400302Z","steps":["trace[2041107124] 'process raft request' (duration: 230.050341ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:08.418406Z","caller":"traceutil/trace.go:171","msg":"trace[21204635] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"130.108529ms","start":"2026-07-14T17:01:08.288285Z","end":"2026-07-14T17:01:08.418394Z","steps":["trace[21204635] 'process raft request' (duration: 130.033927ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:08.58291Z","caller":"traceutil/trace.go:171","msg":"trace[630643389] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"126.260276ms","start":"2026-07-14T17:01:08.456611Z","end":"2026-07-14T17:01:08.582871Z","steps":["trace[630643389] 'process raft request' (duration: 101.886874ms)","trace[630643389] 'compare' (duration: 24.119466ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:08.583066Z","caller":"traceutil/trace.go:171","msg":"trace[1346023518] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"117.350473ms","start":"2026-07-14T17:01:08.465446Z","end":"2026-07-14T17:01:08.582797Z","steps":["trace[1346023518] 'read index received' (duration: 92.98392ms)","trace[1346023518] 'applied index is now lower than readState.Index' (duration: 24.365232ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:08.583302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.832435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T17:01:08.58342Z","caller":"traceutil/trace.go:171","msg":"trace[1344529417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"117.986759ms","start":"2026-07-14T17:01:08.465411Z","end":"2026-07-14T17:01:08.583398Z","steps":["trace[1344529417] 'agreement among raft nodes before linearized reading' (duration: 117.64103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:08.583667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.730075ms","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-14T17:01:08.583718Z","caller":"traceutil/trace.go:171","msg":"trace[171508610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"111.875518ms","start":"2026-07-14T17:01:08.471831Z","end":"2026-07-14T17:01:08.583706Z","steps":["trace[171508610] 'agreement among raft nodes before linearized reading' (duration: 111.55306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:09.677025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184885739302403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:01:09.984737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296089531s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:01:09.984827Z","caller":"traceutil/trace.go:171","msg":"trace[1493009245] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.304911653s","start":"2026-07-14T17:01:08.6799Z","end":"2026-07-14T17:01:09.984812Z","steps":["trace[1493009245] 'compare' (duration: 1.295718732s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:09.984875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:08.679885Z","time spent":"1.304967835s","remote":"127.0.0.1:34550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:01:09.987911Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.299330517s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T17:01:09.988402Z","caller":"traceutil/trace.go:171","msg":"trace[1394015427] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.308135379s","start":"2026-07-14T17:01:08.680246Z","end":"2026-07-14T17:01:09.988381Z","steps":["trace[1394015427] 'process raft request' (duration: 1.307924902s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:09.988463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:08.680236Z","time spent":"1.3082018s","remote":"127.0.0.1:34550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:01:09.98844Z","caller":"traceutil/trace.go:171","msg":"trace[1027060988] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:599; }","duration":"811.889542ms","start":"2026-07-14T17:01:09.176524Z","end":"2026-07-14T17:01:09.988414Z","steps":["trace[1027060988] 'read index received' (duration: 811.566543ms)","trace[1027060988] 'applied index is now lower than readState.Index' (duration: 322.129µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:09.98848Z","caller":"traceutil/trace.go:171","msg":"trace[1880214908] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.307237075s","start":"2026-07-14T17:01:08.681198Z","end":"2026-07-14T17:01:09.988435Z","steps":["trace[1880214908] 'process raft request' (duration: 1.307111511s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:09.988645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:08.681173Z","time spent":"1.307365239s","remote":"127.0.0.1:34730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:01:09.988743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.236815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:09.988771Z","caller":"traceutil/trace.go:171","msg":"trace[2091918918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"529.292366ms","start":"2026-07-14T17:01:09.459468Z","end":"2026-07-14T17:01:09.98876Z","steps":["trace[2091918918] 'agreement among raft nodes before linearized reading' (duration: 529.250485ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:09.988777Z","caller":"traceutil/trace.go:171","msg":"trace[1265032018] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.306338611s","start":"2026-07-14T17:01:08.682426Z","end":"2026-07-14T17:01:09.988764Z","steps":["trace[1265032018] 'process raft request' (duration: 1.305942021s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:09.988821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.306713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T17:01:09.988839Z","caller":"traceutil/trace.go:171","msg":"trace[1263430969] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:584; }","duration":"812.357605ms","start":"2026-07-14T17:01:09.176476Z","end":"2026-07-14T17:01:09.988833Z","steps":["trace[1263430969] 'agreement among raft nodes before linearized reading' (duration: 812.325164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:09.988858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:09.176457Z","time spent":"812.397515ms","remote":"127.0.0.1:34502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.229\" "} {"level":"warn","ts":"2026-07-14T17:01:09.988884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:08.682399Z","time spent":"1.306411083s","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:01:09.988789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:09.459452Z","time spent":"529.332998ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:01:22.807547Z","caller":"traceutil/trace.go:171","msg":"trace[2030497444] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"103.737663ms","start":"2026-07-14T17:01:22.703789Z","end":"2026-07-14T17:01:22.807526Z","steps":["trace[2030497444] 'read index received' (duration: 103.5994ms)","trace[2030497444] 'applied index is now lower than readState.Index' (duration: 137.373µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:22.807723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.916448ms","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-14T17:01:22.807748Z","caller":"traceutil/trace.go:171","msg":"trace[1598920695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:726; }","duration":"103.97932ms","start":"2026-07-14T17:01:22.703759Z","end":"2026-07-14T17:01:22.807739Z","steps":["trace[1598920695] 'agreement among raft nodes before linearized reading' (duration: 103.879697ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:22.807829Z","caller":"traceutil/trace.go:171","msg":"trace[1962424573] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"104.481264ms","start":"2026-07-14T17:01:22.70334Z","end":"2026-07-14T17:01:22.807822Z","steps":["trace[1962424573] 'process raft request' (duration: 104.090023ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:24.347355Z","caller":"traceutil/trace.go:171","msg":"trace[1594597171] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"138.066818ms","start":"2026-07-14T17:01:24.209256Z","end":"2026-07-14T17:01:24.347323Z","steps":["trace[1594597171] 'read index received' (duration: 136.304391ms)","trace[1594597171] 'applied index is now lower than readState.Index' (duration: 1.760647ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:24.347437Z","caller":"traceutil/trace.go:171","msg":"trace[653333647] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"182.465048ms","start":"2026-07-14T17:01:24.164925Z","end":"2026-07-14T17:01:24.34739Z","steps":["trace[653333647] 'process raft request' (duration: 181.277026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:24.347682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.388597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z7tzm\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-14T17:01:24.347737Z","caller":"traceutil/trace.go:171","msg":"trace[358258106] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-z7tzm; range_end:; response_count:1; response_revision:737; }","duration":"138.52627ms","start":"2026-07-14T17:01:24.209195Z","end":"2026-07-14T17:01:24.347721Z","steps":["trace[358258106] 'agreement among raft nodes before linearized reading' (duration: 138.289914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:25.007506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184885739302710,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:01:25.183964Z","caller":"traceutil/trace.go:171","msg":"trace[1809355758] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"677.017428ms","start":"2026-07-14T17:01:24.506925Z","end":"2026-07-14T17:01:25.183943Z","steps":["trace[1809355758] 'read index received' (duration: 676.761601ms)","trace[1809355758] 'applied index is now lower than readState.Index' (duration: 255.137µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:25.18406Z","caller":"traceutil/trace.go:171","msg":"trace[277499366] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"809.855559ms","start":"2026-07-14T17:01:24.374197Z","end":"2026-07-14T17:01:25.184052Z","steps":["trace[277499366] 'process raft request' (duration: 809.575851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:25.184294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:24.374159Z","time spent":"809.92705ms","remote":"127.0.0.1:34674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:01:25.184333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.156875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:25.184423Z","caller":"traceutil/trace.go:171","msg":"trace[168600863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"468.292018ms","start":"2026-07-14T17:01:24.716105Z","end":"2026-07-14T17:01:25.184397Z","steps":["trace[168600863] 'agreement among raft nodes before linearized reading' (duration: 468.155235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:25.184479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:24.716072Z","time spent":"468.398451ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:01:25.184496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.647915ms","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-14T17:01:25.184531Z","caller":"traceutil/trace.go:171","msg":"trace[2039353290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:738; }","duration":"359.729447ms","start":"2026-07-14T17:01:24.824792Z","end":"2026-07-14T17:01:25.184522Z","steps":["trace[2039353290] 'agreement among raft nodes before linearized reading' (duration: 359.563452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:25.184561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:24.824775Z","time spent":"359.780358ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:01:25.184546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.599982ms","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-14T17:01:25.184639Z","caller":"traceutil/trace.go:171","msg":"trace[471139844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:738; }","duration":"677.744688ms","start":"2026-07-14T17:01:24.506874Z","end":"2026-07-14T17:01:25.184618Z","steps":["trace[471139844] 'agreement among raft nodes before linearized reading' (duration: 677.517371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:25.184687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:24.50685Z","time spent":"677.82809ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T17:01:25.18514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.58573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T17:01:25.185257Z","caller":"traceutil/trace.go:171","msg":"trace[779789615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"372.204446ms","start":"2026-07-14T17:01:24.813022Z","end":"2026-07-14T17:01:25.185226Z","steps":["trace[779789615] 'agreement among raft nodes before linearized reading' (duration: 371.348554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:25.185312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:24.812988Z","time spent":"372.3118ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T17:01:29.496822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.036095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:01:29.496979Z","caller":"traceutil/trace.go:171","msg":"trace[1926838210] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"216.219658ms","start":"2026-07-14T17:01:29.280737Z","end":"2026-07-14T17:01:29.496957Z","steps":["trace[1926838210] 'read index received' (duration: 90.916836ms)","trace[1926838210] 'applied index is now lower than readState.Index' (duration: 125.301052ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:29.497113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.398812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-vp68c\" ","response":"range_response_count:1 size:5393"} {"level":"warn","ts":"2026-07-14T17:01:29.497181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.76229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-oidc-hmac\" ","response":"range_response_count:1 size:452"} {"level":"info","ts":"2026-07-14T17:01:29.497217Z","caller":"traceutil/trace.go:171","msg":"trace[1559589767] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-oidc-hmac; range_end:; response_count:1; response_revision:787; }","duration":"266.820421ms","start":"2026-07-14T17:01:29.230386Z","end":"2026-07-14T17:01:29.497207Z","steps":["trace[1559589767] 'agreement among raft nodes before linearized reading' (duration: 266.655706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:29.497348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.437184ms","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-14T17:01:29.497401Z","caller":"traceutil/trace.go:171","msg":"trace[720164292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"287.499006ms","start":"2026-07-14T17:01:29.209887Z","end":"2026-07-14T17:01:29.497386Z","steps":["trace[720164292] 'agreement among raft nodes before linearized reading' (duration: 287.379843ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:29.497381Z","caller":"traceutil/trace.go:171","msg":"trace[1459597266] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"266.751699ms","start":"2026-07-14T17:01:29.230596Z","end":"2026-07-14T17:01:29.497347Z","steps":["trace[1459597266] 'process raft request' (duration: 141.070156ms)","trace[1459597266] 'compare' (duration: 124.83168ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:29.4975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.777862ms","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-14T17:01:29.497568Z","caller":"traceutil/trace.go:171","msg":"trace[2074088861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:787; }","duration":"287.973178ms","start":"2026-07-14T17:01:29.209579Z","end":"2026-07-14T17:01:29.497553Z","steps":["trace[2074088861] 'agreement among raft nodes before linearized reading' (duration: 287.725332ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:29.497199Z","caller":"traceutil/trace.go:171","msg":"trace[1650651709] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-vp68c; range_end:; response_count:1; response_revision:787; }","duration":"260.514445ms","start":"2026-07-14T17:01:29.23666Z","end":"2026-07-14T17:01:29.497174Z","steps":["trace[1650651709] 'agreement among raft nodes before linearized reading' (duration: 260.303989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:29.503408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.031009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:29.503479Z","caller":"traceutil/trace.go:171","msg":"trace[128871084] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:0; response_revision:789; }","duration":"173.150882ms","start":"2026-07-14T17:01:29.33031Z","end":"2026-07-14T17:01:29.503461Z","steps":["trace[128871084] 'agreement among raft nodes before linearized reading' (duration: 173.027349ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:29.503652Z","caller":"traceutil/trace.go:171","msg":"trace[292070564] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"221.959378ms","start":"2026-07-14T17:01:29.281682Z","end":"2026-07-14T17:01:29.503641Z","steps":["trace[292070564] 'process raft request' (duration: 221.439474ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:29.503876Z","caller":"traceutil/trace.go:171","msg":"trace[2101027114] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"217.615604ms","start":"2026-07-14T17:01:29.28625Z","end":"2026-07-14T17:01:29.503865Z","steps":["trace[2101027114] 'process raft request' (duration: 217.005918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:59.619763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.162104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:01:59.619921Z","caller":"traceutil/trace.go:171","msg":"trace[192860620] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"429.333573ms","start":"2026-07-14T17:01:59.190568Z","end":"2026-07-14T17:01:59.619902Z","steps":["trace[192860620] 'process raft request' (duration: 102.946283ms)","trace[192860620] 'compare' (duration: 325.914167ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:59.619967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:59.190533Z","time spent":"429.412705ms","remote":"127.0.0.1:34502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:02:00.160556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184885739303164,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:02:00.267767Z","caller":"traceutil/trace.go:171","msg":"trace[791643272] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"627.276288ms","start":"2026-07-14T17:01:59.640463Z","end":"2026-07-14T17:02:00.267739Z","steps":["trace[791643272] 'process raft request' (duration: 627.022951ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:00.268052Z","caller":"traceutil/trace.go:171","msg":"trace[1960696125] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:981; }","duration":"607.857876ms","start":"2026-07-14T17:01:59.660181Z","end":"2026-07-14T17:02:00.268039Z","steps":["trace[1960696125] 'read index received' (duration: 607.304611ms)","trace[1960696125] 'applied index is now lower than readState.Index' (duration: 552.165µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:00.268316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.438971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T17:02:00.268363Z","caller":"traceutil/trace.go:171","msg":"trace[1154035212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"597.526203ms","start":"2026-07-14T17:01:59.670823Z","end":"2026-07-14T17:02:00.268349Z","steps":["trace[1154035212] 'agreement among raft nodes before linearized reading' (duration: 597.327238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:00.268397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:59.670787Z","time spent":"597.603105ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T17:02:00.268413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.200189ms","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-14T17:02:00.268448Z","caller":"traceutil/trace.go:171","msg":"trace[316606869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"344.274191ms","start":"2026-07-14T17:01:59.924163Z","end":"2026-07-14T17:02:00.268437Z","steps":["trace[316606869] 'agreement among raft nodes before linearized reading' (duration: 344.179648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:00.268476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:59.924136Z","time spent":"344.333023ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T17:02:00.268469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.245475ms","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-14T17:02:00.268564Z","caller":"traceutil/trace.go:171","msg":"trace[301616215] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"132.646095ms","start":"2026-07-14T17:02:00.135908Z","end":"2026-07-14T17:02:00.268554Z","steps":["trace[301616215] 'process raft request' (duration: 132.08128ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:00.268586Z","caller":"traceutil/trace.go:171","msg":"trace[1182852199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"608.40108ms","start":"2026-07-14T17:01:59.660156Z","end":"2026-07-14T17:02:00.268557Z","steps":["trace[1182852199] 'agreement among raft nodes before linearized reading' (duration: 607.978089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:00.268638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:59.660143Z","time spent":"608.484792ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:02:00.268594Z","caller":"traceutil/trace.go:171","msg":"trace[1197948703] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"442.385896ms","start":"2026-07-14T17:01:59.826151Z","end":"2026-07-14T17:02:00.268537Z","steps":["trace[1197948703] 'process raft request' (duration: 441.77605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:00.268941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:59.826103Z","time spent":"442.702965ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:02:00.268954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:59.64042Z","time spent":"627.416803ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:22.662355Z","caller":"traceutil/trace.go:171","msg":"trace[976936119] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"263.206597ms","start":"2026-07-14T17:02:22.399125Z","end":"2026-07-14T17:02:22.662331Z","steps":["trace[976936119] 'process raft request' (duration: 256.912731ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:22.662427Z","caller":"traceutil/trace.go:171","msg":"trace[1948071902] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"262.305194ms","start":"2026-07-14T17:02:22.400099Z","end":"2026-07-14T17:02:22.662405Z","steps":["trace[1948071902] 'process raft request' (duration: 262.161799ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:22.662678Z","caller":"traceutil/trace.go:171","msg":"trace[890202331] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1060; }","duration":"204.315702ms","start":"2026-07-14T17:02:22.458337Z","end":"2026-07-14T17:02:22.662653Z","steps":["trace[890202331] 'read index received' (duration: 197.705387ms)","trace[890202331] 'applied index is now lower than readState.Index' (duration: 6.609445ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:22.66312Z","caller":"traceutil/trace.go:171","msg":"trace[773970261] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"262.390986ms","start":"2026-07-14T17:02:22.400712Z","end":"2026-07-14T17:02:22.663103Z","steps":["trace[773970261] 'process raft request' (duration: 261.656327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:22.663295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.931188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:02:22.663501Z","caller":"traceutil/trace.go:171","msg":"trace[519028851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"205.208805ms","start":"2026-07-14T17:02:22.458278Z","end":"2026-07-14T17:02:22.663487Z","steps":["trace[519028851] 'agreement among raft nodes before linearized reading' (duration: 204.873377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:38.729886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.460659ms","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-14T17:02:38.729978Z","caller":"traceutil/trace.go:171","msg":"trace[1330998434] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1126; }","duration":"166.558821ms","start":"2026-07-14T17:02:38.563388Z","end":"2026-07-14T17:02:38.729947Z","steps":["trace[1330998434] 'range keys from in-memory index tree' (duration: 166.337836ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:40.873111Z","caller":"traceutil/trace.go:171","msg":"trace[311488302] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"113.439567ms","start":"2026-07-14T17:02:40.759649Z","end":"2026-07-14T17:02:40.873089Z","steps":["trace[311488302] 'read index received' (duration: 113.367125ms)","trace[311488302] 'applied index is now lower than readState.Index' (duration: 71.822µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:40.873132Z","caller":"traceutil/trace.go:171","msg":"trace[29667394] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"371.125659ms","start":"2026-07-14T17:02:40.50199Z","end":"2026-07-14T17:02:40.873116Z","steps":["trace[29667394] 'process raft request' (duration: 370.967845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:40.87327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.618743ms","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-14T17:02:40.8733Z","caller":"traceutil/trace.go:171","msg":"trace[1922404080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1133; }","duration":"113.674084ms","start":"2026-07-14T17:02:40.759616Z","end":"2026-07-14T17:02:40.873291Z","steps":["trace[1922404080] 'agreement among raft nodes before linearized reading' (duration: 113.53979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:40.873357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.563289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T17:02:40.873378Z","caller":"traceutil/trace.go:171","msg":"trace[1799040227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1133; }","duration":"108.5948ms","start":"2026-07-14T17:02:40.764778Z","end":"2026-07-14T17:02:40.873372Z","steps":["trace[1799040227] 'agreement among raft nodes before linearized reading' (duration: 108.519798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:40.873271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.501974Z","time spent":"371.235993ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:02:40.873417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.249964ms","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-14T17:02:40.873463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.752173ms","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-14T17:02:40.873483Z","caller":"traceutil/trace.go:171","msg":"trace[1650638410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1133; }","duration":"108.783595ms","start":"2026-07-14T17:02:40.764694Z","end":"2026-07-14T17:02:40.873478Z","steps":["trace[1650638410] 'agreement among raft nodes before linearized reading' (duration: 108.727743ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:40.873491Z","caller":"traceutil/trace.go:171","msg":"trace[754963513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1133; }","duration":"110.347517ms","start":"2026-07-14T17:02:40.763123Z","end":"2026-07-14T17:02:40.873471Z","steps":["trace[754963513] 'agreement among raft nodes before linearized reading' (duration: 110.113031ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:41.292325Z","caller":"traceutil/trace.go:171","msg":"trace[734658763] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"414.652521ms","start":"2026-07-14T17:02:40.877648Z","end":"2026-07-14T17:02:41.2923Z","steps":["trace[734658763] 'process raft request' (duration: 407.061212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:41.292458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.877626Z","time spent":"414.764404ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:41.307685Z","caller":"traceutil/trace.go:171","msg":"trace[554330768] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"423.429442ms","start":"2026-07-14T17:02:40.884239Z","end":"2026-07-14T17:02:41.307669Z","steps":["trace[554330768] 'process raft request' (duration: 423.36442ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:41.307722Z","caller":"traceutil/trace.go:171","msg":"trace[2048775798] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"425.657111ms","start":"2026-07-14T17:02:40.882046Z","end":"2026-07-14T17:02:41.307703Z","steps":["trace[2048775798] 'process raft request' (duration: 425.525237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:41.307816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.882019Z","time spent":"425.746182ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:02:41.307814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.884231Z","time spent":"423.515484ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:41.307872Z","caller":"traceutil/trace.go:171","msg":"trace[1804474844] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1176; }","duration":"363.373916ms","start":"2026-07-14T17:02:40.944474Z","end":"2026-07-14T17:02:41.307848Z","steps":["trace[1804474844] 'read index received' (duration: 340.250109ms)","trace[1804474844] 'applied index is now lower than readState.Index' (duration: 23.121977ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:41.307685Z","caller":"traceutil/trace.go:171","msg":"trace[329426417] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"429.090711ms","start":"2026-07-14T17:02:40.87857Z","end":"2026-07-14T17:02:41.30766Z","steps":["trace[329426417] 'process raft request' (duration: 428.880205ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:41.307903Z","caller":"traceutil/trace.go:171","msg":"trace[1105433297] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"296.775608ms","start":"2026-07-14T17:02:41.011114Z","end":"2026-07-14T17:02:41.30789Z","steps":["trace[1105433297] 'process raft request' (duration: 296.522802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:41.307961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.878556Z","time spent":"429.374147ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:02:41.308047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.553342ms","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-14T17:02:41.308076Z","caller":"traceutil/trace.go:171","msg":"trace[1046263580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1138; }","duration":"363.645164ms","start":"2026-07-14T17:02:40.944423Z","end":"2026-07-14T17:02:41.308068Z","steps":["trace[1046263580] 'agreement among raft nodes before linearized reading' (duration: 363.501589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:41.3081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.944405Z","time spent":"363.689504ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:02:57.692347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.736129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:02:57.692456Z","caller":"traceutil/trace.go:171","msg":"trace[1798141369] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"241.005975ms","start":"2026-07-14T17:02:57.451435Z","end":"2026-07-14T17:02:57.692441Z","steps":["trace[1798141369] 'read index received' (duration: 51.020729ms)","trace[1798141369] 'applied index is now lower than readState.Index' (duration: 189.983696ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:57.692526Z","caller":"traceutil/trace.go:171","msg":"trace[555863605] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"293.81641ms","start":"2026-07-14T17:02:57.398699Z","end":"2026-07-14T17:02:57.692515Z","steps":["trace[555863605] 'process raft request' (duration: 103.818934ms)","trace[555863605] 'compare' (duration: 189.536954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:57.692836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.386365ms","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-14T17:02:57.692921Z","caller":"traceutil/trace.go:171","msg":"trace[1510158865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1210; }","duration":"241.517468ms","start":"2026-07-14T17:02:57.451383Z","end":"2026-07-14T17:02:57.6929Z","steps":["trace[1510158865] 'agreement among raft nodes before linearized reading' (duration: 241.285782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:57.74007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.635882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:02:57.740089Z","caller":"traceutil/trace.go:171","msg":"trace[1057073943] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"159.796703ms","start":"2026-07-14T17:02:57.580264Z","end":"2026-07-14T17:02:57.740061Z","steps":["trace[1057073943] 'process raft request' (duration: 159.576827ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:57.740152Z","caller":"traceutil/trace.go:171","msg":"trace[812247296] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1211; }","duration":"278.752765ms","start":"2026-07-14T17:02:57.461381Z","end":"2026-07-14T17:02:57.740133Z","steps":["trace[812247296] 'agreement among raft nodes before linearized reading' (duration: 278.611831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:57.740137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.94894ms","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-14T17:02:57.740209Z","caller":"traceutil/trace.go:171","msg":"trace[890900674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"287.057472ms","start":"2026-07-14T17:02:57.453133Z","end":"2026-07-14T17:02:57.74019Z","steps":["trace[890900674] 'agreement among raft nodes before linearized reading' (duration: 286.831797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:57.740225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.859973ms","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-14T17:02:57.740282Z","caller":"traceutil/trace.go:171","msg":"trace[1564541529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"282.953815ms","start":"2026-07-14T17:02:57.457313Z","end":"2026-07-14T17:02:57.740267Z","steps":["trace[1564541529] 'agreement among raft nodes before linearized reading' (duration: 282.704379ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:58.276359Z","caller":"traceutil/trace.go:171","msg":"trace[674495373] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"578.789509ms","start":"2026-07-14T17:02:57.697546Z","end":"2026-07-14T17:02:58.276336Z","steps":["trace[674495373] 'process raft request' (duration: 526.374953ms)","trace[674495373] 'compare' (duration: 52.309013ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:58.276501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:57.69753Z","time spent":"578.904151ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:58.324635Z","caller":"traceutil/trace.go:171","msg":"trace[1007922313] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"579.995561ms","start":"2026-07-14T17:02:57.744616Z","end":"2026-07-14T17:02:58.324612Z","steps":["trace[1007922313] 'process raft request' (duration: 579.789665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:58.324763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:57.744592Z","time spent":"580.109904ms","remote":"127.0.0.1:34550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:02:58.325301Z","caller":"traceutil/trace.go:171","msg":"trace[1036563611] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"580.291179ms","start":"2026-07-14T17:02:57.744994Z","end":"2026-07-14T17:02:58.325285Z","steps":["trace[1036563611] 'process raft request' (duration: 580.259378ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:58.325352Z","caller":"traceutil/trace.go:171","msg":"trace[480483490] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"580.674679ms","start":"2026-07-14T17:02:57.744656Z","end":"2026-07-14T17:02:58.325331Z","steps":["trace[480483490] 'process raft request' (duration: 579.892288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:58.325405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:57.744986Z","time spent":"580.373341ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:02:58.325451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:57.744627Z","time spent":"580.766101ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:58.325484Z","caller":"traceutil/trace.go:171","msg":"trace[959021689] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"580.569165ms","start":"2026-07-14T17:02:57.744899Z","end":"2026-07-14T17:02:58.325468Z","steps":["trace[959021689] 'process raft request' (duration: 580.281488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:58.32652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:57.744875Z","time spent":"580.675357ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:03:09.683157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2788184885739303927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:03:09.931656Z","caller":"traceutil/trace.go:171","msg":"trace[1597133559] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1340; }","duration":"748.524433ms","start":"2026-07-14T17:03:09.183111Z","end":"2026-07-14T17:03:09.931636Z","steps":["trace[1597133559] 'read index received' (duration: 748.41262ms)","trace[1597133559] 'applied index is now lower than readState.Index' (duration: 111.103µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:09.932106Z","caller":"traceutil/trace.go:171","msg":"trace[676260378] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"832.114727ms","start":"2026-07-14T17:03:09.099979Z","end":"2026-07-14T17:03:09.932094Z","steps":["trace[676260378] 'process raft request' (duration: 831.490561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:09.932293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:09.099953Z","time spent":"832.182189ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:03:09.932418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.51085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:09.932473Z","caller":"traceutil/trace.go:171","msg":"trace[1525001363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"472.618253ms","start":"2026-07-14T17:03:09.459843Z","end":"2026-07-14T17:03:09.932461Z","steps":["trace[1525001363] 'agreement among raft nodes before linearized reading' (duration: 472.52181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:09.932498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:09.459812Z","time spent":"472.682394ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:03:09.932668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.56179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T17:03:09.932684Z","caller":"traceutil/trace.go:171","msg":"trace[1402897378] range","detail":"{range_begin:/registry/masterleases/199.19.213.229; range_end:; response_count:1; response_revision:1293; }","duration":"749.639872ms","start":"2026-07-14T17:03:09.18304Z","end":"2026-07-14T17:03:09.93268Z","steps":["trace[1402897378] 'agreement among raft nodes before linearized reading' (duration: 749.56724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:09.932696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:09.183013Z","time spent":"749.680332ms","remote":"127.0.0.1:34502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.229\" "} {"level":"warn","ts":"2026-07-14T17:03:09.932919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.454558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T17:03:09.932936Z","caller":"traceutil/trace.go:171","msg":"trace[1638952995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"190.503059ms","start":"2026-07-14T17:03:09.742429Z","end":"2026-07-14T17:03:09.932932Z","steps":["trace[1638952995] 'agreement among raft nodes before linearized reading' (duration: 190.460388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:09.932993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.811402ms","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-14T17:03:09.933004Z","caller":"traceutil/trace.go:171","msg":"trace[405990046] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1293; }","duration":"250.869413ms","start":"2026-07-14T17:03:09.682131Z","end":"2026-07-14T17:03:09.933001Z","steps":["trace[405990046] 'agreement among raft nodes before linearized reading' (duration: 250.836542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:09.933057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.204317ms","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-14T17:03:09.933069Z","caller":"traceutil/trace.go:171","msg":"trace[1812200564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1293; }","duration":"420.254849ms","start":"2026-07-14T17:03:09.512812Z","end":"2026-07-14T17:03:09.933066Z","steps":["trace[1812200564] 'agreement among raft nodes before linearized reading' (duration: 420.219038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:09.93308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:09.512793Z","time spent":"420.285089ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:07:21.788644Z","caller":"traceutil/trace.go:171","msg":"trace[1664157361] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"112.052989ms","start":"2026-07-14T17:07:21.676565Z","end":"2026-07-14T17:07:21.788618Z","steps":["trace[1664157361] 'process raft request' (duration: 111.887245ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:07:22.095145Z","caller":"traceutil/trace.go:171","msg":"trace[56139784] transaction","detail":"{read_only:false; response_revision:2475; number_of_response:1; }","duration":"415.557784ms","start":"2026-07-14T17:07:21.679558Z","end":"2026-07-14T17:07:22.095116Z","steps":["trace[56139784] 'process raft request' (duration: 415.512633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:22.095308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:21.679546Z","time spent":"415.693337ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:07:22.095424Z","caller":"traceutil/trace.go:171","msg":"trace[1388311816] transaction","detail":"{read_only:false; response_revision:2474; number_of_response:1; }","duration":"418.4818ms","start":"2026-07-14T17:07:21.676906Z","end":"2026-07-14T17:07:22.095388Z","steps":["trace[1388311816] 'process raft request' (duration: 418.025999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:22.095573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:21.676887Z","time spent":"418.610323ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:07:29.203329Z","caller":"traceutil/trace.go:171","msg":"trace[2077575176] linearizableReadLoop","detail":"{readStateIndex:2610; appliedIndex:2609; }","duration":"203.803175ms","start":"2026-07-14T17:07:28.999508Z","end":"2026-07-14T17:07:29.203311Z","steps":["trace[2077575176] 'read index received' (duration: 203.608311ms)","trace[2077575176] 'applied index is now lower than readState.Index' (duration: 193.734µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:07:29.203385Z","caller":"traceutil/trace.go:171","msg":"trace[1378555632] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"393.617672ms","start":"2026-07-14T17:07:28.809735Z","end":"2026-07-14T17:07:29.203353Z","steps":["trace[1378555632] 'process raft request' (duration: 393.451248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:29.203563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.899538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-14T17:07:29.203587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.066892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-14T17:07:29.203626Z","caller":"traceutil/trace.go:171","msg":"trace[182975023] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2509; }","duration":"204.012811ms","start":"2026-07-14T17:07:28.999597Z","end":"2026-07-14T17:07:29.20361Z","steps":["trace[182975023] 'agreement among raft nodes before linearized reading' (duration: 203.839217ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:07:29.203677Z","caller":"traceutil/trace.go:171","msg":"trace[816912491] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2509; }","duration":"204.208947ms","start":"2026-07-14T17:07:28.999449Z","end":"2026-07-14T17:07:29.203658Z","steps":["trace[816912491] 'agreement among raft nodes before linearized reading' (duration: 203.936319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:29.203818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:28.809706Z","time spent":"393.89696ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:07:39.827357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.81069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:07:39.827493Z","caller":"traceutil/trace.go:171","msg":"trace[619426411] linearizableReadLoop","detail":"{readStateIndex:2669; appliedIndex:2668; }","duration":"402.330722ms","start":"2026-07-14T17:07:39.42514Z","end":"2026-07-14T17:07:39.827471Z","steps":["trace[619426411] 'read index received' (duration: 82.152322ms)","trace[619426411] 'applied index is now lower than readState.Index' (duration: 320.17662ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:07:39.827624Z","caller":"traceutil/trace.go:171","msg":"trace[2087251609] transaction","detail":"{read_only:false; response_revision:2564; number_of_response:1; }","duration":"403.981597ms","start":"2026-07-14T17:07:39.423615Z","end":"2026-07-14T17:07:39.827597Z","steps":["trace[2087251609] 'process raft request' (duration: 83.754485ms)","trace[2087251609] 'compare' (duration: 319.682607ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:07:39.827703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:39.423601Z","time spent":"404.066519ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:07:39.827714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.560468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-14T17:07:39.827765Z","caller":"traceutil/trace.go:171","msg":"trace[1036720216] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2564; }","duration":"402.63036ms","start":"2026-07-14T17:07:39.425119Z","end":"2026-07-14T17:07:39.82775Z","steps":["trace[1036720216] 'agreement among raft nodes before linearized reading' (duration: 402.442465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:39.827819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:39.42511Z","time spent":"402.689772ms","remote":"127.0.0.1:34762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-14T17:07:39.831503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.864626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:07:39.831539Z","caller":"traceutil/trace.go:171","msg":"trace[1224696407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2565; }","duration":"334.903087ms","start":"2026-07-14T17:07:39.496628Z","end":"2026-07-14T17:07:39.831531Z","steps":["trace[1224696407] 'agreement among raft nodes before linearized reading' (duration: 334.839385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:39.831563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:39.496578Z","time spent":"334.979428ms","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:07:39.831682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.769073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:07:39.831789Z","caller":"traceutil/trace.go:171","msg":"trace[217922390] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"307.329581ms","start":"2026-07-14T17:07:39.524432Z","end":"2026-07-14T17:07:39.831761Z","steps":["trace[217922390] 'process raft request' (duration: 306.970442ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:07:39.831818Z","caller":"traceutil/trace.go:171","msg":"trace[1180192023] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2565; }","duration":"318.920296ms","start":"2026-07-14T17:07:39.512855Z","end":"2026-07-14T17:07:39.831775Z","steps":["trace[1180192023] 'agreement among raft nodes before linearized reading' (duration: 318.713391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:39.831869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:39.512843Z","time spent":"319.01463ms","remote":"127.0.0.1:34556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:07:39.831941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:39.524417Z","time spent":"307.430105ms","remote":"127.0.0.1:34664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7189,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:07:39.831744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.440352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-07-14T17:07:39.832023Z","caller":"traceutil/trace.go:171","msg":"trace[1707221884] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:2565; }","duration":"332.711829ms","start":"2026-07-14T17:07:39.49928Z","end":"2026-07-14T17:07:39.831992Z","steps":["trace[1707221884] 'agreement among raft nodes before linearized reading' (duration: 332.432211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:39.832099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:39.499273Z","time spent":"332.801311ms","remote":"127.0.0.1:34836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":716,"request content":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" "} {"level":"warn","ts":"2026-07-14T17:07:39.832175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.985271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-07-14T17:07:39.832249Z","caller":"traceutil/trace.go:171","msg":"trace[982660908] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:2565; }","duration":"313.201365ms","start":"2026-07-14T17:07:39.519036Z","end":"2026-07-14T17:07:39.832238Z","steps":["trace[982660908] 'agreement among raft nodes before linearized reading' (duration: 312.850977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:39.832344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:39.519019Z","time spent":"313.316319ms","remote":"127.0.0.1:34848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":702,"request content":"key:\"/registry/clusterrolebindings/cluster-admin\" "}