{"level":"warn","ts":"2026-10-09T09:14:24.175319Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T09:14:24.175415Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.25: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://162.253.55.25:2380","--initial-cluster=instance=https://162.253.55.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.25: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-10-09T09:14:24.175483Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T09:14:24.175491Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.25:2380"]} {"level":"info","ts":"2026-10-09T09:14:24.175509Z","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-10-09T09:14:24.176148Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.25:2379"]} {"level":"info","ts":"2026-10-09T09:14:24.176218Z","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":16,"max-cpu-available":16,"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://162.253.55.25:2380"],"listen-peer-urls":["https://162.253.55.25:2380"],"advertise-client-urls":["https://162.253.55.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.25: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-10-09T09:14:24.180177Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.773504ms"} {"level":"info","ts":"2026-10-09T09:14:24.190538Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"afeb1bf8b692f9e8","cluster-id":"6bfcc13baae99738"} {"level":"info","ts":"2026-10-09T09:14:24.190627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T09:14:24.190645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became follower at term 0"} {"level":"info","ts":"2026-10-09T09:14:24.190652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft afeb1bf8b692f9e8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T09:14:24.190671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became follower at term 1"} {"level":"info","ts":"2026-10-09T09:14:24.190703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 switched to configuration voters=(12676256331193317864)"} {"level":"warn","ts":"2026-10-09T09:14:24.196896Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T09:14:24.198389Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T09:14:24.199617Z","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-10-09T09:14:24.201832Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"afeb1bf8b692f9e8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T09:14:24.202363Z","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-10-09T09:14:24.202495Z","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-10-09T09:14:24.202507Z","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-10-09T09:14:24.202478Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"afeb1bf8b692f9e8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T09:14:24.204843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 switched to configuration voters=(12676256331193317864)"} {"level":"info","ts":"2026-10-09T09:14:24.205021Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6bfcc13baae99738","local-member-id":"afeb1bf8b692f9e8","added-peer-id":"afeb1bf8b692f9e8","added-peer-peer-urls":["https://162.253.55.25:2380"]} {"level":"info","ts":"2026-10-09T09:14:24.206508Z","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-10-09T09:14:24.206714Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"afeb1bf8b692f9e8","initial-advertise-peer-urls":["https://162.253.55.25:2380"],"listen-peer-urls":["https://162.253.55.25:2380"],"advertise-client-urls":["https://162.253.55.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T09:14:24.206745Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T09:14:24.206855Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.25:2380"} {"level":"info","ts":"2026-10-09T09:14:24.20687Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.25:2380"} {"level":"info","ts":"2026-10-09T09:14:25.190983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T09:14:25.191065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T09:14:25.191159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 received MsgPreVoteResp from afeb1bf8b692f9e8 at term 1"} {"level":"info","ts":"2026-10-09T09:14:25.191239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became candidate at term 2"} {"level":"info","ts":"2026-10-09T09:14:25.191252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 received MsgVoteResp from afeb1bf8b692f9e8 at term 2"} {"level":"info","ts":"2026-10-09T09:14:25.191317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"afeb1bf8b692f9e8 became leader at term 2"} {"level":"info","ts":"2026-10-09T09:14:25.191341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: afeb1bf8b692f9e8 elected leader afeb1bf8b692f9e8 at term 2"} {"level":"info","ts":"2026-10-09T09:14:25.19275Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T09:14:25.192722Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T09:14:25.192741Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T09:14:25.193455Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"afeb1bf8b692f9e8","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.25:2379]}","request-path":"/0/members/afeb1bf8b692f9e8/attributes","cluster-id":"6bfcc13baae99738","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T09:14:25.193774Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T09:14:25.193888Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T09:14:25.194508Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6bfcc13baae99738","local-member-id":"afeb1bf8b692f9e8","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T09:14:25.194672Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T09:14:25.194727Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T09:14:25.196101Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-09T09:14:25.19645Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.25:2379"} {"level":"warn","ts":"2026-10-09T09:15:16.402981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.755061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:15:16.403308Z","caller":"traceutil/trace.go:171","msg":"trace[460191501] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"292.810419ms","start":"2026-10-09T09:15:16.110466Z","end":"2026-10-09T09:15:16.403276Z","steps":["trace[460191501] 'process raft request' (duration: 150.012715ms)","trace[460191501] 'compare' (duration: 141.600918ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:15:16.403308Z","caller":"traceutil/trace.go:171","msg":"trace[273274295] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"237.823033ms","start":"2026-10-09T09:15:16.165424Z","end":"2026-10-09T09:15:16.403247Z","steps":["trace[273274295] 'process raft request' (duration: 237.698171ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:15:16.819511Z","caller":"traceutil/trace.go:171","msg":"trace[1924518707] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"262.141026ms","start":"2026-10-09T09:15:16.557334Z","end":"2026-10-09T09:15:16.819475Z","steps":["trace[1924518707] 'process raft request' (duration: 261.87222ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:15:16.987231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.067146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:15:16.987438Z","caller":"traceutil/trace.go:171","msg":"trace[1863349545] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:632; }","duration":"376.865244ms","start":"2026-10-09T09:15:16.610545Z","end":"2026-10-09T09:15:16.98741Z","steps":["trace[1863349545] 'read index received' (duration: 208.584412ms)","trace[1863349545] 'applied index is now lower than readState.Index' (duration: 168.279422ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:15:16.987643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.097338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6drz6\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-09T09:15:16.987637Z","caller":"traceutil/trace.go:171","msg":"trace[1170723815] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"429.60431ms","start":"2026-10-09T09:15:16.557923Z","end":"2026-10-09T09:15:16.987527Z","steps":["trace[1170723815] 'process raft request' (duration: 305.126784ms)","trace[1170723815] 'compare' (duration: 123.883372ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:15:16.987745Z","caller":"traceutil/trace.go:171","msg":"trace[766511545] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6drz6; range_end:; response_count:1; response_revision:610; }","duration":"377.237802ms","start":"2026-10-09T09:15:16.61049Z","end":"2026-10-09T09:15:16.987727Z","steps":["trace[766511545] 'agreement among raft nodes before linearized reading' (duration: 377.027147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:15:16.987845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:15:16.610473Z","time spent":"377.358865ms","remote":"127.0.0.1:35714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-6drz6\" "} {"level":"warn","ts":"2026-10-09T09:15:16.988056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:15:16.557892Z","time spent":"429.931807ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T09:15:17.012848Z","caller":"traceutil/trace.go:171","msg":"trace[570103504] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"114.708907ms","start":"2026-10-09T09:15:16.89804Z","end":"2026-10-09T09:15:17.012749Z","steps":["trace[570103504] 'process raft request' (duration: 113.274155ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:16:54.160761Z","caller":"traceutil/trace.go:171","msg":"trace[2144247717] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"229.609163ms","start":"2026-10-09T09:16:53.931123Z","end":"2026-10-09T09:16:54.160732Z","steps":["trace[2144247717] 'read index received' (duration: 182.705927ms)","trace[2144247717] 'applied index is now lower than readState.Index' (duration: 46.901626ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:16:54.160998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.863918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1045291a-7cb1-47b3-9c65-8bd2b6a15556\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-09T09:16:54.16109Z","caller":"traceutil/trace.go:171","msg":"trace[1538187905] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1045291a-7cb1-47b3-9c65-8bd2b6a15556; range_end:; response_count:1; response_revision:1871; }","duration":"230.011252ms","start":"2026-10-09T09:16:53.931058Z","end":"2026-10-09T09:16:54.161069Z","steps":["trace[1538187905] 'agreement among raft nodes before linearized reading' (duration: 229.778327ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:16:54.35412Z","caller":"traceutil/trace.go:171","msg":"trace[343119685] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"165.324848ms","start":"2026-10-09T09:16:54.188748Z","end":"2026-10-09T09:16:54.354072Z","steps":["trace[343119685] 'process raft request' (duration: 113.092544ms)","trace[343119685] 'compare' (duration: 51.857646ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:17:07.135921Z","caller":"traceutil/trace.go:171","msg":"trace[1524810038] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"121.264065ms","start":"2026-10-09T09:17:07.014626Z","end":"2026-10-09T09:17:07.13589Z","steps":["trace[1524810038] 'process raft request' (duration: 86.297255ms)","trace[1524810038] 'compare' (duration: 34.860327ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:17:10.479545Z","caller":"traceutil/trace.go:171","msg":"trace[1013078624] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"102.773519ms","start":"2026-10-09T09:17:10.376741Z","end":"2026-10-09T09:17:10.479514Z","steps":["trace[1013078624] 'process raft request' (duration: 102.550184ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:17:43.227027Z","caller":"traceutil/trace.go:171","msg":"trace[879001333] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"164.854255ms","start":"2026-10-09T09:17:43.06214Z","end":"2026-10-09T09:17:43.226994Z","steps":["trace[879001333] 'process raft request' (duration: 164.554658ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:17:43.340834Z","caller":"traceutil/trace.go:171","msg":"trace[893290027] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"191.307208ms","start":"2026-10-09T09:17:43.149478Z","end":"2026-10-09T09:17:43.340785Z","steps":["trace[893290027] 'process raft request' (duration: 137.546026ms)","trace[893290027] 'compare' (duration: 53.501056ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:17:48.857453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.27869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-10-09T09:17:48.857538Z","caller":"traceutil/trace.go:171","msg":"trace[1143051143] range","detail":"{range_begin:/registry/masterleases/162.253.55.25; range_end:; response_count:1; response_revision:2216; }","duration":"171.434313ms","start":"2026-10-09T09:17:48.686087Z","end":"2026-10-09T09:17:48.857521Z","steps":["trace[1143051143] 'range keys from in-memory index tree' (duration: 171.094085ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:17:48.857501Z","caller":"traceutil/trace.go:171","msg":"trace[891235075] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"148.417778ms","start":"2026-10-09T09:17:48.709043Z","end":"2026-10-09T09:17:48.857461Z","steps":["trace[891235075] 'process raft request' (duration: 98.466941ms)","trace[891235075] 'compare' (duration: 49.802754ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:17:49.627969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.976805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:17:49.628095Z","caller":"traceutil/trace.go:171","msg":"trace[350456797] linearizableReadLoop","detail":"{readStateIndex:2304; appliedIndex:2303; }","duration":"252.463205ms","start":"2026-10-09T09:17:49.375615Z","end":"2026-10-09T09:17:49.628078Z","steps":["trace[350456797] 'read index received' (duration: 63.229425ms)","trace[350456797] 'applied index is now lower than readState.Index' (duration: 189.23253ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:17:49.628241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.636729ms","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-10-09T09:17:49.628199Z","caller":"traceutil/trace.go:171","msg":"trace[901848414] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"264.722428ms","start":"2026-10-09T09:17:49.363441Z","end":"2026-10-09T09:17:49.628164Z","steps":["trace[901848414] 'process raft request' (duration: 75.465537ms)","trace[901848414] 'compare' (duration: 188.858692ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:17:49.628279Z","caller":"traceutil/trace.go:171","msg":"trace[1594688878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2220; }","duration":"252.722811ms","start":"2026-10-09T09:17:49.375547Z","end":"2026-10-09T09:17:49.628269Z","steps":["trace[1594688878] 'agreement among raft nodes before linearized reading' (duration: 252.601058ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:17:49.651885Z","caller":"traceutil/trace.go:171","msg":"trace[1476069246] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"138.221161ms","start":"2026-10-09T09:17:49.513633Z","end":"2026-10-09T09:17:49.651854Z","steps":["trace[1476069246] 'process raft request' (duration: 138.042677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:17:49.651982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.879343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T09:17:49.652075Z","caller":"traceutil/trace.go:171","msg":"trace[1914742962] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2221; }","duration":"159.029926ms","start":"2026-10-09T09:17:49.493024Z","end":"2026-10-09T09:17:49.652054Z","steps":["trace[1914742962] 'agreement among raft nodes before linearized reading' (duration: 158.816951ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:18:23.955889Z","caller":"traceutil/trace.go:171","msg":"trace[1711937412] linearizableReadLoop","detail":"{readStateIndex:2680; appliedIndex:2679; }","duration":"101.336826ms","start":"2026-10-09T09:18:23.854533Z","end":"2026-10-09T09:18:23.95587Z","steps":["trace[1711937412] 'read index received' (duration: 101.188473ms)","trace[1711937412] 'applied index is now lower than readState.Index' (duration: 146.973µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:18:23.955951Z","caller":"traceutil/trace.go:171","msg":"trace[1527328525] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"166.072003ms","start":"2026-10-09T09:18:23.789846Z","end":"2026-10-09T09:18:23.955918Z","steps":["trace[1527328525] 'process raft request' (duration: 165.889659ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:18:23.956133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.850299ms","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-10-09T09:18:23.956175Z","caller":"traceutil/trace.go:171","msg":"trace[1301174250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2583; }","duration":"131.949761ms","start":"2026-10-09T09:18:23.824212Z","end":"2026-10-09T09:18:23.956162Z","steps":["trace[1301174250] 'agreement among raft nodes before linearized reading' (duration: 131.808718ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:27.761893Z","caller":"traceutil/trace.go:171","msg":"trace[267225978] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"101.671283ms","start":"2026-10-09T09:23:27.660186Z","end":"2026-10-09T09:23:27.761857Z","steps":["trace[267225978] 'process raft request' (duration: 101.467658ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:30.210491Z","caller":"traceutil/trace.go:171","msg":"trace[656193529] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"102.818488ms","start":"2026-10-09T09:23:30.107648Z","end":"2026-10-09T09:23:30.210467Z","steps":["trace[656193529] 'process raft request' (duration: 62.296083ms)","trace[656193529] 'compare' (duration: 40.30868ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:23:34.478243Z","caller":"traceutil/trace.go:171","msg":"trace[1774693645] linearizableReadLoop","detail":"{readStateIndex:5434; appliedIndex:5433; }","duration":"125.615918ms","start":"2026-10-09T09:23:34.352602Z","end":"2026-10-09T09:23:34.478218Z","steps":["trace[1774693645] 'read index received' (duration: 64.628445ms)","trace[1774693645] 'applied index is now lower than readState.Index' (duration: 60.986383ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:23:34.478544Z","caller":"traceutil/trace.go:171","msg":"trace[6740933] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"178.409168ms","start":"2026-10-09T09:23:34.300102Z","end":"2026-10-09T09:23:34.478492Z","steps":["trace[6740933] 'process raft request' (duration: 117.19241ms)","trace[6740933] 'compare' (duration: 60.82374ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:23:34.478689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.408906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:23:34.479436Z","caller":"traceutil/trace.go:171","msg":"trace[730845863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5235; }","duration":"119.193783ms","start":"2026-10-09T09:23:34.360227Z","end":"2026-10-09T09:23:34.479421Z","steps":["trace[730845863] 'agreement among raft nodes before linearized reading' (duration: 118.327974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:34.478821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.20079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-10-09T09:23:34.479681Z","caller":"traceutil/trace.go:171","msg":"trace[80969802] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5235; }","duration":"127.10405ms","start":"2026-10-09T09:23:34.352548Z","end":"2026-10-09T09:23:34.479652Z","steps":["trace[80969802] 'agreement among raft nodes before linearized reading' (duration: 126.109069ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:34.796175Z","caller":"traceutil/trace.go:171","msg":"trace[596580175] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"116.376071ms","start":"2026-10-09T09:23:34.679767Z","end":"2026-10-09T09:23:34.796144Z","steps":["trace[596580175] 'process raft request' (duration: 46.225583ms)","trace[596580175] 'compare' (duration: 70.018055ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:23:46.102967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.457146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:23:46.103202Z","caller":"traceutil/trace.go:171","msg":"trace[150071768] linearizableReadLoop","detail":"{readStateIndex:5623; appliedIndex:5622; }","duration":"294.033592ms","start":"2026-10-09T09:23:45.809135Z","end":"2026-10-09T09:23:46.103168Z","steps":["trace[150071768] 'read index received' (duration: 5.284788ms)","trace[150071768] 'applied index is now lower than readState.Index' (duration: 288.745904ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:23:46.103238Z","caller":"traceutil/trace.go:171","msg":"trace[597768746] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"321.837934ms","start":"2026-10-09T09:23:45.781321Z","end":"2026-10-09T09:23:46.103159Z","steps":["trace[597768746] 'process raft request' (duration: 33.116641ms)","trace[597768746] 'compare' (duration: 288.363234ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:23:46.103434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.277017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T09:23:46.103429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:23:45.781299Z","time spent":"322.029138ms","remote":"127.0.0.1:35714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7956,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T09:23:46.103483Z","caller":"traceutil/trace.go:171","msg":"trace[1914079348] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:5420; }","duration":"294.39097ms","start":"2026-10-09T09:23:45.809078Z","end":"2026-10-09T09:23:46.103469Z","steps":["trace[1914079348] 'agreement among raft nodes before linearized reading' (duration: 294.250127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:46.10354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.034113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-09T09:23:46.103663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.605541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T09:23:46.103661Z","caller":"traceutil/trace.go:171","msg":"trace[817783671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5420; }","duration":"222.188687ms","start":"2026-10-09T09:23:45.881452Z","end":"2026-10-09T09:23:46.10364Z","steps":["trace[817783671] 'agreement among raft nodes before linearized reading' (duration: 221.980562ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:46.103747Z","caller":"traceutil/trace.go:171","msg":"trace[1258982269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5420; }","duration":"173.712183ms","start":"2026-10-09T09:23:45.93002Z","end":"2026-10-09T09:23:46.103732Z","steps":["trace[1258982269] 'agreement among raft nodes before linearized reading' (duration: 173.436747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:46.103673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.516192ms","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-10-09T09:23:46.103863Z","caller":"traceutil/trace.go:171","msg":"trace[739169686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5420; }","duration":"179.740257ms","start":"2026-10-09T09:23:45.924108Z","end":"2026-10-09T09:23:46.103848Z","steps":["trace[739169686] 'agreement among raft nodes before linearized reading' (duration: 179.373459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:53.227852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.251001ms","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-10-09T09:23:53.227995Z","caller":"traceutil/trace.go:171","msg":"trace[1269332552] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5590; }","duration":"189.414954ms","start":"2026-10-09T09:23:53.038561Z","end":"2026-10-09T09:23:53.227976Z","steps":["trace[1269332552] 'range keys from in-memory index tree' (duration: 189.22937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:53.227902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.881806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-22481a59-536e-43d4-9635-572e591beaef\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-09T09:23:53.228303Z","caller":"traceutil/trace.go:171","msg":"trace[470808122] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-22481a59-536e-43d4-9635-572e591beaef; range_end:; response_count:1; response_revision:5590; }","duration":"131.322556ms","start":"2026-10-09T09:23:53.096945Z","end":"2026-10-09T09:23:53.228268Z","steps":["trace[470808122] 'range keys from in-memory index tree' (duration: 130.669731ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:53.228972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.609161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-10-09T09:23:53.229153Z","caller":"traceutil/trace.go:171","msg":"trace[1873078006] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5590; }","duration":"156.824075ms","start":"2026-10-09T09:23:53.072309Z","end":"2026-10-09T09:23:53.229133Z","steps":["trace[1873078006] 'range keys from in-memory index tree' (duration: 155.601618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:53.570838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.125026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110786"} {"level":"info","ts":"2026-10-09T09:23:53.570924Z","caller":"traceutil/trace.go:171","msg":"trace[1236321478] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5592; }","duration":"240.253679ms","start":"2026-10-09T09:23:53.330652Z","end":"2026-10-09T09:23:53.570906Z","steps":["trace[1236321478] 'range keys from in-memory index tree' (duration: 239.716997ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:53.632668Z","caller":"traceutil/trace.go:171","msg":"trace[1805085211] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"281.578514ms","start":"2026-10-09T09:23:53.351068Z","end":"2026-10-09T09:23:53.632646Z","steps":["trace[1805085211] 'process raft request' (duration: 281.360379ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:23:53.632651Z","caller":"traceutil/trace.go:171","msg":"trace[113424056] linearizableReadLoop","detail":"{readStateIndex:5802; appliedIndex:5801; }","duration":"279.534608ms","start":"2026-10-09T09:23:53.353063Z","end":"2026-10-09T09:23:53.632598Z","steps":["trace[113424056] 'read index received' (duration: 279.452156ms)","trace[113424056] 'applied index is now lower than readState.Index' (duration: 81.382µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:23:53.632835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.750122ms","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-10-09T09:23:53.632894Z","caller":"traceutil/trace.go:171","msg":"trace[1651619824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5593; }","duration":"279.855615ms","start":"2026-10-09T09:23:53.35302Z","end":"2026-10-09T09:23:53.632876Z","steps":["trace[1651619824] 'agreement among raft nodes before linearized reading' (duration: 279.688191ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:23:53.641347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.790625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:23:53.641418Z","caller":"traceutil/trace.go:171","msg":"trace[1830023909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5594; }","duration":"280.900148ms","start":"2026-10-09T09:23:53.360502Z","end":"2026-10-09T09:23:53.641402Z","steps":["trace[1830023909] 'agreement among raft nodes before linearized reading' (duration: 280.778825ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:24:04.298234Z","caller":"traceutil/trace.go:171","msg":"trace[2116028300] transaction","detail":"{read_only:false; response_revision:5734; number_of_response:1; }","duration":"100.147629ms","start":"2026-10-09T09:24:04.198062Z","end":"2026-10-09T09:24:04.29821Z","steps":["trace[2116028300] 'process raft request' (duration: 77.237276ms)","trace[2116028300] 'compare' (duration: 22.441202ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:24:25.228876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941} {"level":"info","ts":"2026-10-09T09:24:25.308842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"78.582716ms","hash":845611959,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":32382976,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T09:24:25.308912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":845611959,"revision":2941,"compact-revision":-1} {"level":"info","ts":"2026-10-09T09:24:46.64276Z","caller":"traceutil/trace.go:171","msg":"trace[1556263159] transaction","detail":"{read_only:false; response_revision:6092; number_of_response:1; }","duration":"124.641515ms","start":"2026-10-09T09:24:46.518077Z","end":"2026-10-09T09:24:46.642718Z","steps":["trace[1556263159] 'process raft request' (duration: 124.486482ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:24:46.64277Z","caller":"traceutil/trace.go:171","msg":"trace[1823193771] transaction","detail":"{read_only:false; response_revision:6091; number_of_response:1; }","duration":"124.880081ms","start":"2026-10-09T09:24:46.517816Z","end":"2026-10-09T09:24:46.642696Z","steps":["trace[1823193771] 'process raft request' (duration: 28.280272ms)","trace[1823193771] 'compare' (duration: 96.258881ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:24:46.642841Z","caller":"traceutil/trace.go:171","msg":"trace[265357820] transaction","detail":"{read_only:false; response_revision:6093; number_of_response:1; }","duration":"123.711775ms","start":"2026-10-09T09:24:46.519097Z","end":"2026-10-09T09:24:46.642809Z","steps":["trace[265357820] 'process raft request' (duration: 123.569322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:24:46.643026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.567271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6084"} {"level":"info","ts":"2026-10-09T09:24:46.643078Z","caller":"traceutil/trace.go:171","msg":"trace[2024602506] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:6093; }","duration":"123.659234ms","start":"2026-10-09T09:24:46.519406Z","end":"2026-10-09T09:24:46.643065Z","steps":["trace[2024602506] 'agreement among raft nodes before linearized reading' (duration: 123.46942ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:24:46.642816Z","caller":"traceutil/trace.go:171","msg":"trace[1056294216] linearizableReadLoop","detail":"{readStateIndex:6316; appliedIndex:6313; }","duration":"123.349547ms","start":"2026-10-09T09:24:46.519439Z","end":"2026-10-09T09:24:46.642789Z","steps":["trace[1056294216] 'read index received' (duration: 26.668336ms)","trace[1056294216] 'applied index is now lower than readState.Index' (duration: 96.679911ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:24:46.849599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.864436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-10-09T09:24:46.849704Z","caller":"traceutil/trace.go:171","msg":"trace[933867972] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:6093; }","duration":"102.02873ms","start":"2026-10-09T09:24:46.747655Z","end":"2026-10-09T09:24:46.849684Z","steps":["trace[933867972] 'range keys from in-memory index tree' (duration: 100.943515ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:29:25.236552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5868} {"level":"info","ts":"2026-10-09T09:29:25.340308Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5868,"took":"101.711441ms","hash":960288504,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":26353664,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T09:29:25.340391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":960288504,"revision":5868,"compact-revision":2941} {"level":"warn","ts":"2026-10-09T09:30:10.405798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.418804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:30:10.405896Z","caller":"traceutil/trace.go:171","msg":"trace[332113927] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8462; }","duration":"234.575307ms","start":"2026-10-09T09:30:10.171299Z","end":"2026-10-09T09:30:10.405875Z","steps":["trace[332113927] 'count revisions from in-memory index tree' (duration: 234.282701ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:30:32.177127Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022bb.wal"} {"level":"warn","ts":"2026-10-09T09:30:52.523936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.322106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:30:52.52402Z","caller":"traceutil/trace.go:171","msg":"trace[807818436] linearizableReadLoop","detail":"{readStateIndex:9051; appliedIndex:9050; }","duration":"335.315907ms","start":"2026-10-09T09:30:52.188691Z","end":"2026-10-09T09:30:52.524007Z","steps":["trace[807818436] 'read index received' (duration: 180.822478ms)","trace[807818436] 'applied index is now lower than readState.Index' (duration: 154.492669ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:30:52.524124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.43104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T09:30:52.524098Z","caller":"traceutil/trace.go:171","msg":"trace[175428258] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"379.866352ms","start":"2026-10-09T09:30:52.144195Z","end":"2026-10-09T09:30:52.524062Z","steps":["trace[175428258] 'process raft request' (duration: 225.338681ms)","trace[175428258] 'compare' (duration: 154.217544ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:30:52.524176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.672404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:30:52.524191Z","caller":"traceutil/trace.go:171","msg":"trace[885757969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8740; }","duration":"163.724855ms","start":"2026-10-09T09:30:52.360462Z","end":"2026-10-09T09:30:52.524187Z","steps":["trace[885757969] 'agreement among raft nodes before linearized reading' (duration: 163.699305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:30:52.524263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:30:52.144174Z","time spent":"380.000314ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T09:30:52.524146Z","caller":"traceutil/trace.go:171","msg":"trace[1149448612] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8740; }","duration":"335.515371ms","start":"2026-10-09T09:30:52.188624Z","end":"2026-10-09T09:30:52.524139Z","steps":["trace[1149448612] 'agreement among raft nodes before linearized reading' (duration: 335.44929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:30:52.524382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.348367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T09:30:52.524423Z","caller":"traceutil/trace.go:171","msg":"trace[240155349] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8740; }","duration":"154.427368ms","start":"2026-10-09T09:30:52.369989Z","end":"2026-10-09T09:30:52.524417Z","steps":["trace[240155349] 'agreement among raft nodes before linearized reading' (duration: 154.344087ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:30:52.524472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T09:30:52.188604Z","time spent":"335.788228ms","remote":"127.0.0.1:36056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":43,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-10-09T09:30:55.190002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.420543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T09:30:55.190111Z","caller":"traceutil/trace.go:171","msg":"trace[461949351] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:8754; }","duration":"255.592557ms","start":"2026-10-09T09:30:54.934493Z","end":"2026-10-09T09:30:55.190085Z","steps":["trace[461949351] 'count revisions from in-memory index tree' (duration: 255.29725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:30:55.1904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.020077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:30:55.190781Z","caller":"traceutil/trace.go:171","msg":"trace[467400928] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"238.354392ms","start":"2026-10-09T09:30:54.952407Z","end":"2026-10-09T09:30:55.190761Z","steps":["trace[467400928] 'process raft request' (duration: 105.832963ms)","trace[467400928] 'compare' (duration: 131.595038ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:30:55.191037Z","caller":"traceutil/trace.go:171","msg":"trace[609499962] transaction","detail":"{read_only:false; response_revision:8756; number_of_response:1; }","duration":"192.331674ms","start":"2026-10-09T09:30:54.998686Z","end":"2026-10-09T09:30:55.191018Z","steps":["trace[609499962] 'process raft request' (duration: 192.256053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:31:03.47998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.937681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T09:31:03.480103Z","caller":"traceutil/trace.go:171","msg":"trace[1165631561] linearizableReadLoop","detail":"{readStateIndex:9114; appliedIndex:9113; }","duration":"115.955119ms","start":"2026-10-09T09:31:03.36413Z","end":"2026-10-09T09:31:03.480085Z","steps":["trace[1165631561] 'read index received' (duration: 11.749662ms)","trace[1165631561] 'applied index is now lower than readState.Index' (duration: 104.203627ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:31:03.480417Z","caller":"traceutil/trace.go:171","msg":"trace[2145559705] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"142.361319ms","start":"2026-10-09T09:31:03.338038Z","end":"2026-10-09T09:31:03.480399Z","steps":["trace[2145559705] 'process raft request' (duration: 37.905797ms)","trace[2145559705] 'compare' (duration: 103.693815ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:31:03.480466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.319646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:31:03.480571Z","caller":"traceutil/trace.go:171","msg":"trace[1496551279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8801; }","duration":"116.48022ms","start":"2026-10-09T09:31:03.364067Z","end":"2026-10-09T09:31:03.480547Z","steps":["trace[1496551279] 'agreement among raft nodes before linearized reading' (duration: 116.332327ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:31:05.286923Z","caller":"traceutil/trace.go:171","msg":"trace[390624569] transaction","detail":"{read_only:false; response_revision:8817; number_of_response:1; }","duration":"145.876877ms","start":"2026-10-09T09:31:05.140989Z","end":"2026-10-09T09:31:05.286866Z","steps":["trace[390624569] 'process raft request' (duration: 61.009233ms)","trace[390624569] 'compare' (duration: 84.406654ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:32:33.17915Z","caller":"traceutil/trace.go:171","msg":"trace[447468089] transaction","detail":"{read_only:false; response_revision:9659; number_of_response:1; }","duration":"125.815372ms","start":"2026-10-09T09:32:33.053302Z","end":"2026-10-09T09:32:33.179117Z","steps":["trace[447468089] 'process raft request' (duration: 125.641998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:32:33.467196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.029444ms","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-10-09T09:32:33.467293Z","caller":"traceutil/trace.go:171","msg":"trace[1703554019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9659; }","duration":"125.173987ms","start":"2026-10-09T09:32:33.342099Z","end":"2026-10-09T09:32:33.467273Z","steps":["trace[1703554019] 'range keys from in-memory index tree' (duration: 124.863311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:32:33.467393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.692667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:32:33.467524Z","caller":"traceutil/trace.go:171","msg":"trace[1977136850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9659; }","duration":"107.83623ms","start":"2026-10-09T09:32:33.359628Z","end":"2026-10-09T09:32:33.467464Z","steps":["trace[1977136850] 'range keys from in-memory index tree' (duration: 107.628156ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:32:33.467606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.053799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-09T09:32:33.467581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.683238ms","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-10-09T09:32:33.467641Z","caller":"traceutil/trace.go:171","msg":"trace[1690452961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9659; }","duration":"127.128721ms","start":"2026-10-09T09:32:33.340501Z","end":"2026-10-09T09:32:33.46763Z","steps":["trace[1690452961] 'range keys from in-memory index tree' (duration: 126.876055ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:32:33.467689Z","caller":"traceutil/trace.go:171","msg":"trace[564719152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9659; }","duration":"129.816531ms","start":"2026-10-09T09:32:33.337826Z","end":"2026-10-09T09:32:33.467643Z","steps":["trace[564719152] 'range keys from in-memory index tree' (duration: 129.507934ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:32:33.599326Z","caller":"traceutil/trace.go:171","msg":"trace[1126687338] transaction","detail":"{read_only:false; response_revision:9661; number_of_response:1; }","duration":"126.005136ms","start":"2026-10-09T09:32:33.473286Z","end":"2026-10-09T09:32:33.599291Z","steps":["trace[1126687338] 'process raft request' (duration: 125.696239ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:32:33.599731Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"afeb1bf8b692f9e8","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T09:32:33.59975Z","caller":"traceutil/trace.go:171","msg":"trace[504538305] transaction","detail":"{read_only:false; response_revision:9662; number_of_response:1; }","duration":"126.352583ms","start":"2026-10-09T09:32:33.473385Z","end":"2026-10-09T09:32:33.599738Z","steps":["trace[504538305] 'process raft request' (duration: 125.840132ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:32:33.599982Z","caller":"traceutil/trace.go:171","msg":"trace[1998969863] transaction","detail":"{read_only:false; response_revision:9663; number_of_response:1; }","duration":"125.987345ms","start":"2026-10-09T09:32:33.473948Z","end":"2026-10-09T09:32:33.599936Z","steps":["trace[1998969863] 'process raft request' (duration: 125.692109ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:32:33.688264Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-10-09T09:32:33.688434Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-10-09T09:34:25.24293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8042} {"level":"info","ts":"2026-10-09T09:34:25.315626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8042,"took":"69.8612ms","hash":1824897544,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":28467200,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T09:34:25.315728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1824897544,"revision":8042,"compact-revision":5868} {"level":"info","ts":"2026-10-09T09:38:15.58868Z","caller":"traceutil/trace.go:171","msg":"trace[660761889] linearizableReadLoop","detail":"{readStateIndex:12524; appliedIndex:12523; }","duration":"227.138477ms","start":"2026-10-09T09:38:15.361509Z","end":"2026-10-09T09:38:15.588648Z","steps":["trace[660761889] 'read index received' (duration: 226.906542ms)","trace[660761889] 'applied index is now lower than readState.Index' (duration: 230.665µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:38:15.588782Z","caller":"traceutil/trace.go:171","msg":"trace[1419894027] transaction","detail":"{read_only:false; response_revision:12107; number_of_response:1; }","duration":"253.775133ms","start":"2026-10-09T09:38:15.334978Z","end":"2026-10-09T09:38:15.588753Z","steps":["trace[1419894027] 'process raft request' (duration: 253.427475ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:38:15.588913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.387112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:38:15.588958Z","caller":"traceutil/trace.go:171","msg":"trace[2039010591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12107; }","duration":"227.520705ms","start":"2026-10-09T09:38:15.361425Z","end":"2026-10-09T09:38:15.588945Z","steps":["trace[2039010591] 'agreement among raft nodes before linearized reading' (duration: 227.417423ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:39:25.250915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10454} {"level":"info","ts":"2026-10-09T09:39:25.342112Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10454,"took":"88.516728ms","hash":42412384,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":25919488,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-09T09:39:25.342161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":42412384,"revision":10454,"compact-revision":8042} {"level":"info","ts":"2026-10-09T09:44:25.257636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12582} {"level":"info","ts":"2026-10-09T09:44:25.351532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12582,"took":"90.703456ms","hash":3294592022,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":26972160,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-09T09:44:25.351621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3294592022,"revision":12582,"compact-revision":10454} {"level":"info","ts":"2026-10-09T09:44:44.795135Z","caller":"traceutil/trace.go:171","msg":"trace[66572375] transaction","detail":"{read_only:false; response_revision:14706; number_of_response:1; }","duration":"123.686422ms","start":"2026-10-09T09:44:44.671415Z","end":"2026-10-09T09:44:44.795101Z","steps":["trace[66572375] 'process raft request' (duration: 121.677427ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:44:53.870068Z","caller":"traceutil/trace.go:171","msg":"trace[1309414295] transaction","detail":"{read_only:false; response_revision:14749; number_of_response:1; }","duration":"150.01691ms","start":"2026-10-09T09:44:53.720009Z","end":"2026-10-09T09:44:53.870026Z","steps":["trace[1309414295] 'process raft request' (duration: 149.805995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:45:02.497295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.154589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T09:45:02.497388Z","caller":"traceutil/trace.go:171","msg":"trace[1486089947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14813; }","duration":"136.301842ms","start":"2026-10-09T09:45:02.361066Z","end":"2026-10-09T09:45:02.497368Z","steps":["trace[1486089947] 'range keys from in-memory index tree' (duration: 136.030636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:45:27.336386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.318434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-09T09:45:27.336468Z","caller":"traceutil/trace.go:171","msg":"trace[1796622644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15137; }","duration":"291.431047ms","start":"2026-10-09T09:45:27.045012Z","end":"2026-10-09T09:45:27.336443Z","steps":["trace[1796622644] 'range keys from in-memory index tree' (duration: 291.177471ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:45:27.336245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.035192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-shjlt\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-10-09T09:45:27.336967Z","caller":"traceutil/trace.go:171","msg":"trace[414249491] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-shjlt; range_end:; response_count:1; response_revision:15137; }","duration":"198.800368ms","start":"2026-10-09T09:45:27.138134Z","end":"2026-10-09T09:45:27.336934Z","steps":["trace[414249491] 'range keys from in-memory index tree' (duration: 197.860628ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:46:19.735149Z","caller":"traceutil/trace.go:171","msg":"trace[703658041] transaction","detail":"{read_only:false; response_revision:15767; number_of_response:1; }","duration":"101.766737ms","start":"2026-10-09T09:46:19.633349Z","end":"2026-10-09T09:46:19.735116Z","steps":["trace[703658041] 'process raft request' (duration: 101.522922ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:46:43.361868Z","caller":"traceutil/trace.go:171","msg":"trace[1596744857] transaction","detail":"{read_only:false; response_revision:15894; number_of_response:1; }","duration":"107.558857ms","start":"2026-10-09T09:46:43.254283Z","end":"2026-10-09T09:46:43.361842Z","steps":["trace[1596744857] 'process raft request' (duration: 107.402874ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:46:43.387082Z","caller":"traceutil/trace.go:171","msg":"trace[1462667333] transaction","detail":"{read_only:false; response_revision:15895; number_of_response:1; }","duration":"121.548929ms","start":"2026-10-09T09:46:43.265507Z","end":"2026-10-09T09:46:43.387056Z","steps":["trace[1462667333] 'process raft request' (duration: 121.348605ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:46:44.730715Z","caller":"traceutil/trace.go:171","msg":"trace[858739013] linearizableReadLoop","detail":"{readStateIndex:16447; appliedIndex:16446; }","duration":"170.353984ms","start":"2026-10-09T09:46:44.560331Z","end":"2026-10-09T09:46:44.730685Z","steps":["trace[858739013] 'read index received' (duration: 113.862µs)","trace[858739013] 'applied index is now lower than readState.Index' (duration: 170.238302ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:46:44.730838Z","caller":"traceutil/trace.go:171","msg":"trace[1267664222] transaction","detail":"{read_only:false; response_revision:15899; number_of_response:1; }","duration":"170.486068ms","start":"2026-10-09T09:46:44.560306Z","end":"2026-10-09T09:46:44.730792Z","steps":["trace[1267664222] 'process raft request' (duration: 170.14198ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:46:44.732074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.691905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T09:46:44.732148Z","caller":"traceutil/trace.go:171","msg":"trace[1950342099] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:15899; }","duration":"171.825268ms","start":"2026-10-09T09:46:44.560304Z","end":"2026-10-09T09:46:44.732129Z","steps":["trace[1950342099] 'agreement among raft nodes before linearized reading' (duration: 170.507428ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:46:44.743196Z","caller":"traceutil/trace.go:171","msg":"trace[1620589675] transaction","detail":"{read_only:false; response_revision:15901; number_of_response:1; }","duration":"182.775212ms","start":"2026-10-09T09:46:44.560396Z","end":"2026-10-09T09:46:44.743171Z","steps":["trace[1620589675] 'process raft request' (duration: 182.649959ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:46:44.743243Z","caller":"traceutil/trace.go:171","msg":"trace[371358542] transaction","detail":"{read_only:false; response_revision:15902; number_of_response:1; }","duration":"182.768001ms","start":"2026-10-09T09:46:44.560459Z","end":"2026-10-09T09:46:44.743227Z","steps":["trace[371358542] 'process raft request' (duration: 182.660689ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:46:44.743425Z","caller":"traceutil/trace.go:171","msg":"trace[1662206093] transaction","detail":"{read_only:false; response_revision:15900; number_of_response:1; }","duration":"183.035007ms","start":"2026-10-09T09:46:44.560376Z","end":"2026-10-09T09:46:44.743411Z","steps":["trace[1662206093] 'process raft request' (duration: 182.502196ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:49:25.264212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14604} {"level":"info","ts":"2026-10-09T09:49:25.348081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14604,"took":"81.075871ms","hash":1142968036,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T09:49:25.34816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1142968036,"revision":14604,"compact-revision":12582} {"level":"info","ts":"2026-10-09T09:53:09.565879Z","caller":"traceutil/trace.go:171","msg":"trace[1020666148] transaction","detail":"{read_only:false; response_revision:18675; number_of_response:1; }","duration":"177.630236ms","start":"2026-10-09T09:53:09.38822Z","end":"2026-10-09T09:53:09.565851Z","steps":["trace[1020666148] 'process raft request' (duration: 177.444952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:53:09.871322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.252453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T09:53:09.871465Z","caller":"traceutil/trace.go:171","msg":"trace[1212403000] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18675; }","duration":"224.493998ms","start":"2026-10-09T09:53:09.64694Z","end":"2026-10-09T09:53:09.871434Z","steps":["trace[1212403000] 'count revisions from in-memory index tree' (duration: 223.973136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:53:09.871525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.72088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-mndz8\" ","response":"range_response_count:1 size:22018"} {"level":"info","ts":"2026-10-09T09:53:09.871889Z","caller":"traceutil/trace.go:171","msg":"trace[1086428769] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-mndz8; range_end:; response_count:1; response_revision:18675; }","duration":"141.980235ms","start":"2026-10-09T09:53:09.729704Z","end":"2026-10-09T09:53:09.871684Z","steps":["trace[1086428769] 'range keys from in-memory index tree' (duration: 141.403513ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:53:10.060874Z","caller":"traceutil/trace.go:171","msg":"trace[1319274491] linearizableReadLoop","detail":"{readStateIndex:19319; appliedIndex:19318; }","duration":"115.919391ms","start":"2026-10-09T09:53:09.944928Z","end":"2026-10-09T09:53:10.060847Z","steps":["trace[1319274491] 'read index received' (duration: 115.630625ms)","trace[1319274491] 'applied index is now lower than readState.Index' (duration: 287.816µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:53:10.060946Z","caller":"traceutil/trace.go:171","msg":"trace[1580698578] transaction","detail":"{read_only:false; response_revision:18676; number_of_response:1; }","duration":"164.645805ms","start":"2026-10-09T09:53:09.89627Z","end":"2026-10-09T09:53:10.060916Z","steps":["trace[1580698578] 'process raft request' (duration: 164.365748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:53:10.061111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.169426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zzlhj\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-10-09T09:53:10.061156Z","caller":"traceutil/trace.go:171","msg":"trace[1152744085] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zzlhj; range_end:; response_count:1; response_revision:18676; }","duration":"116.275689ms","start":"2026-10-09T09:53:09.944867Z","end":"2026-10-09T09:53:10.061142Z","steps":["trace[1152744085] 'agreement among raft nodes before linearized reading' (duration: 116.114645ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:54:09.319612Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"afeb1bf8b692f9e8","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T09:54:09.326736Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-10-09T09:54:09.326924Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-10-09T09:54:25.271438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17040} {"level":"info","ts":"2026-10-09T09:54:25.381404Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17040,"took":"106.06403ms","hash":3389112559,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":32727040,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T09:54:25.381485Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3389112559,"revision":17040,"compact-revision":14604} {"level":"info","ts":"2026-10-09T09:58:52.219794Z","caller":"traceutil/trace.go:171","msg":"trace[1456924585] transaction","detail":"{read_only:false; response_revision:21335; number_of_response:1; }","duration":"116.079691ms","start":"2026-10-09T09:58:52.103672Z","end":"2026-10-09T09:58:52.219752Z","steps":["trace[1456924585] 'process raft request' (duration: 115.883166ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:59:02.818741Z","caller":"traceutil/trace.go:171","msg":"trace[1582397752] transaction","detail":"{read_only:false; response_revision:21392; number_of_response:1; }","duration":"131.824713ms","start":"2026-10-09T09:59:02.686885Z","end":"2026-10-09T09:59:02.81871Z","steps":["trace[1582397752] 'process raft request' (duration: 128.351905ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:59:08.534261Z","caller":"traceutil/trace.go:171","msg":"trace[539688479] transaction","detail":"{read_only:false; response_revision:21419; number_of_response:1; }","duration":"114.386151ms","start":"2026-10-09T09:59:08.419846Z","end":"2026-10-09T09:59:08.534232Z","steps":["trace[539688479] 'process raft request' (duration: 112.262584ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:59:08.534403Z","caller":"traceutil/trace.go:171","msg":"trace[1008102382] transaction","detail":"{read_only:false; response_revision:21420; number_of_response:1; }","duration":"105.265598ms","start":"2026-10-09T09:59:08.429112Z","end":"2026-10-09T09:59:08.534378Z","steps":["trace[1008102382] 'process raft request' (duration: 105.085453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:59:09.147016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.967431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T09:59:09.147759Z","caller":"traceutil/trace.go:171","msg":"trace[1237306989] linearizableReadLoop","detail":"{readStateIndex:22153; appliedIndex:22152; }","duration":"171.770257ms","start":"2026-10-09T09:59:08.97597Z","end":"2026-10-09T09:59:09.14774Z","steps":["trace[1237306989] 'read index received' (duration: 19.879386ms)","trace[1237306989] 'applied index is now lower than readState.Index' (duration: 151.887571ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:59:09.147906Z","caller":"traceutil/trace.go:171","msg":"trace[595886570] transaction","detail":"{read_only:false; response_revision:21421; number_of_response:1; }","duration":"280.433462ms","start":"2026-10-09T09:59:08.86737Z","end":"2026-10-09T09:59:09.147804Z","steps":["trace[595886570] 'process raft request' (duration: 128.59283ms)","trace[595886570] 'compare' (duration: 150.852389ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T09:59:09.148071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.076355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T09:59:09.14818Z","caller":"traceutil/trace.go:171","msg":"trace[376619274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21421; }","duration":"172.248168ms","start":"2026-10-09T09:59:08.975905Z","end":"2026-10-09T09:59:09.148153Z","steps":["trace[376619274] 'agreement among raft nodes before linearized reading' (duration: 171.907121ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:59:09.168714Z","caller":"traceutil/trace.go:171","msg":"trace[421484091] transaction","detail":"{read_only:false; response_revision:21422; number_of_response:1; }","duration":"160.438684ms","start":"2026-10-09T09:59:09.008244Z","end":"2026-10-09T09:59:09.168682Z","steps":["trace[421484091] 'process raft request' (duration: 160.226319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:59:09.169081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.610706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T09:59:09.169181Z","caller":"traceutil/trace.go:171","msg":"trace[565692646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21422; }","duration":"118.764319ms","start":"2026-10-09T09:59:09.050401Z","end":"2026-10-09T09:59:09.169165Z","steps":["trace[565692646] 'agreement among raft nodes before linearized reading' (duration: 118.515454ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:59:09.169272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.312215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-09T09:59:09.169326Z","caller":"traceutil/trace.go:171","msg":"trace[1125085739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21422; }","duration":"120.413607ms","start":"2026-10-09T09:59:09.048897Z","end":"2026-10-09T09:59:09.169311Z","steps":["trace[1125085739] 'agreement among raft nodes before linearized reading' (duration: 120.263183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:59:09.169598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.413048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-09T09:59:09.16967Z","caller":"traceutil/trace.go:171","msg":"trace[402865984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21422; }","duration":"187.53527ms","start":"2026-10-09T09:59:08.982124Z","end":"2026-10-09T09:59:09.16966Z","steps":["trace[402865984] 'agreement among raft nodes before linearized reading' (duration: 187.408338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:59:09.436385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.048289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T09:59:09.436543Z","caller":"traceutil/trace.go:171","msg":"trace[1555364503] transaction","detail":"{read_only:false; response_revision:21424; number_of_response:1; }","duration":"261.761804ms","start":"2026-10-09T09:59:09.17476Z","end":"2026-10-09T09:59:09.436522Z","steps":["trace[1555364503] 'process raft request' (duration: 137.49187ms)","trace[1555364503] 'compare' (duration: 123.68427ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:59:09.436852Z","caller":"traceutil/trace.go:171","msg":"trace[871829825] linearizableReadLoop","detail":"{readStateIndex:22158; appliedIndex:22155; }","duration":"245.842256ms","start":"2026-10-09T09:59:09.190988Z","end":"2026-10-09T09:59:09.436831Z","steps":["trace[871829825] 'read index received' (duration: 121.200124ms)","trace[871829825] 'applied index is now lower than readState.Index' (duration: 124.641152ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T09:59:09.436989Z","caller":"traceutil/trace.go:171","msg":"trace[2065249427] transaction","detail":"{read_only:false; response_revision:21426; number_of_response:1; }","duration":"260.462314ms","start":"2026-10-09T09:59:09.176502Z","end":"2026-10-09T09:59:09.436964Z","steps":["trace[2065249427] 'process raft request' (duration: 260.222629ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T09:59:09.437175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.189394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-09T09:59:09.437264Z","caller":"traceutil/trace.go:171","msg":"trace[1033580696] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:21426; }","duration":"246.316738ms","start":"2026-10-09T09:59:09.190935Z","end":"2026-10-09T09:59:09.437251Z","steps":["trace[1033580696] 'agreement among raft nodes before linearized reading' (duration: 246.057902ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:59:09.436861Z","caller":"traceutil/trace.go:171","msg":"trace[137300319] transaction","detail":"{read_only:false; response_revision:21425; number_of_response:1; }","duration":"261.976978ms","start":"2026-10-09T09:59:09.174842Z","end":"2026-10-09T09:59:09.436819Z","steps":["trace[137300319] 'process raft request' (duration: 261.633801ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T09:59:25.277505Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19496} {"level":"info","ts":"2026-10-09T09:59:25.416502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19496,"took":"130.208776ms","hash":3054718881,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":29356032,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T09:59:25.416638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3054718881,"revision":19496,"compact-revision":17040} {"level":"warn","ts":"2026-10-09T10:03:15.078686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.740057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T10:03:15.078809Z","caller":"traceutil/trace.go:171","msg":"trace[770208438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24040; }","duration":"144.929132ms","start":"2026-10-09T10:03:14.933853Z","end":"2026-10-09T10:03:15.078782Z","steps":["trace[770208438] 'range keys from in-memory index tree' (duration: 144.504733ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:03:15.078963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.615089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T10:03:15.079039Z","caller":"traceutil/trace.go:171","msg":"trace[42058840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24040; }","duration":"150.738412ms","start":"2026-10-09T10:03:14.928283Z","end":"2026-10-09T10:03:15.079021Z","steps":["trace[42058840] 'range keys from in-memory index tree' (duration: 150.416734ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:03:15.080072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.717353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-09T10:03:15.080158Z","caller":"traceutil/trace.go:171","msg":"trace[1915014626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24040; }","duration":"159.849426ms","start":"2026-10-09T10:03:14.92029Z","end":"2026-10-09T10:03:15.08014Z","steps":["trace[1915014626] 'range keys from in-memory index tree' (duration: 158.141248ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:04:25.28452Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21504} {"level":"info","ts":"2026-10-09T10:04:25.395164Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21504,"took":"106.385022ms","hash":3546207446,"current-db-size-bytes":56713216,"current-db-size":"57 MB","current-db-size-in-use-bytes":43200512,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-09T10:04:25.395241Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3546207446,"revision":21504,"compact-revision":19496} {"level":"info","ts":"2026-10-09T10:06:20.956383Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000687d.wal"} {"level":"info","ts":"2026-10-09T10:06:33.086904Z","caller":"traceutil/trace.go:171","msg":"trace[972266576] linearizableReadLoop","detail":"{readStateIndex:26951; appliedIndex:26950; }","duration":"177.656568ms","start":"2026-10-09T10:06:32.909219Z","end":"2026-10-09T10:06:33.086875Z","steps":["trace[972266576] 'read index received' (duration: 114.16586ms)","trace[972266576] 'applied index is now lower than readState.Index' (duration: 63.489268ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:33.086949Z","caller":"traceutil/trace.go:171","msg":"trace[1911374159] transaction","detail":"{read_only:false; response_revision:26092; number_of_response:1; }","duration":"655.06875ms","start":"2026-10-09T10:06:32.431836Z","end":"2026-10-09T10:06:33.086905Z","steps":["trace[1911374159] 'process raft request' (duration: 591.540371ms)","trace[1911374159] 'compare' (duration: 63.266442ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:33.087125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:32.431818Z","time spent":"655.212374ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:33.087502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.230121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:33.088699Z","caller":"traceutil/trace.go:171","msg":"trace[773967888] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:26092; }","duration":"179.465278ms","start":"2026-10-09T10:06:32.90921Z","end":"2026-10-09T10:06:33.088675Z","steps":["trace[773967888] 'agreement among raft nodes before linearized reading' (duration: 177.712119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.931639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.364825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:33.931987Z","caller":"traceutil/trace.go:171","msg":"trace[911847959] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"906.569427ms","start":"2026-10-09T10:06:33.025392Z","end":"2026-10-09T10:06:33.931962Z","steps":["trace[911847959] 'process raft request' (duration: 906.405913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.932076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.025356Z","time spent":"906.67915ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:33.93219Z","caller":"traceutil/trace.go:171","msg":"trace[179521123] linearizableReadLoop","detail":"{readStateIndex:26953; appliedIndex:26951; }","duration":"845.191655ms","start":"2026-10-09T10:06:33.086977Z","end":"2026-10-09T10:06:33.932169Z","steps":["trace[179521123] 'read index received' (duration: 194.146166ms)","trace[179521123] 'applied index is now lower than readState.Index' (duration: 651.044949ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:33.932323Z","caller":"traceutil/trace.go:171","msg":"trace[565337383] transaction","detail":"{read_only:false; response_revision:26093; number_of_response:1; }","duration":"915.063446ms","start":"2026-10-09T10:06:33.017241Z","end":"2026-10-09T10:06:33.932305Z","steps":["trace[565337383] 'process raft request' (duration: 263.866043ms)","trace[565337383] 'compare' (duration: 649.874214ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:33.932353Z","caller":"traceutil/trace.go:171","msg":"trace[383269160] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"762.730844ms","start":"2026-10-09T10:06:33.169611Z","end":"2026-10-09T10:06:33.932342Z","steps":["trace[383269160] 'process raft request' (duration: 762.307315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.932392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.017211Z","time spent":"915.149069ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:33.932468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.169581Z","time spent":"762.798596ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:33.932575Z","caller":"traceutil/trace.go:171","msg":"trace[1766651406] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"665.928032ms","start":"2026-10-09T10:06:33.26664Z","end":"2026-10-09T10:06:33.932568Z","steps":["trace[1766651406] 'process raft request' (duration: 665.460952ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.932608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.266572Z","time spent":"666.018915ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:33.932761Z","caller":"traceutil/trace.go:171","msg":"trace[37310846] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"656.015891ms","start":"2026-10-09T10:06:33.276735Z","end":"2026-10-09T10:06:33.932751Z","steps":["trace[37310846] 'process raft request' (duration: 655.403428ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.932796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.276722Z","time spent":"656.054982ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:33.932882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.484586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:33.932908Z","caller":"traceutil/trace.go:171","msg":"trace[524857144] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26097; }","duration":"906.528157ms","start":"2026-10-09T10:06:33.026371Z","end":"2026-10-09T10:06:33.932899Z","steps":["trace[524857144] 'agreement among raft nodes before linearized reading' (duration: 906.485316ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.932925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.026359Z","time spent":"906.561297ms","remote":"127.0.0.1:45182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:33.933076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.099871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-09T10:06:33.933096Z","caller":"traceutil/trace.go:171","msg":"trace[1361414220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26097; }","duration":"912.167003ms","start":"2026-10-09T10:06:33.020921Z","end":"2026-10-09T10:06:33.933088Z","steps":["trace[1361414220] 'agreement among raft nodes before linearized reading' (duration: 912.082091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.933111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.020894Z","time spent":"912.213534ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-09T10:06:33.93343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010919608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:33.933495Z","caller":"traceutil/trace.go:171","msg":"trace[677969701] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26097; }","duration":"1.011058151s","start":"2026-10-09T10:06:32.92242Z","end":"2026-10-09T10:06:33.933478Z","steps":["trace[677969701] 'agreement among raft nodes before linearized reading' (duration: 1.010918208s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:33.933526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:32.922389Z","time spent":"1.011131533s","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:34.433002Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18007820268603922493,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-09T10:06:34.463817Z","caller":"traceutil/trace.go:171","msg":"trace[2048051061] transaction","detail":"{read_only:false; response_revision:26098; number_of_response:1; }","duration":"1.043833324s","start":"2026-10-09T10:06:33.419948Z","end":"2026-10-09T10:06:34.463781Z","steps":["trace[2048051061] 'process raft request' (duration: 1.043527907s)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:34.464051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.41992Z","time spent":"1.044009807s","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:34.464036Z","caller":"traceutil/trace.go:171","msg":"trace[1568795817] transaction","detail":"{read_only:false; response_revision:26099; number_of_response:1; }","duration":"822.412257ms","start":"2026-10-09T10:06:33.641583Z","end":"2026-10-09T10:06:34.463995Z","steps":["trace[1568795817] 'process raft request' (duration: 822.145352ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:34.464314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.64155Z","time spent":"822.622682ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:34.853248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.85122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:34.853614Z","caller":"traceutil/trace.go:171","msg":"trace[1114885580] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"913.775668ms","start":"2026-10-09T10:06:33.939752Z","end":"2026-10-09T10:06:34.853528Z","steps":["trace[1114885580] 'process raft request' (duration: 629.486729ms)","trace[1114885580] 'compare' (duration: 283.558003ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:34.853844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.939723Z","time spent":"913.999714ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:35.082793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.409273ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T10:06:35.083209Z","caller":"traceutil/trace.go:171","msg":"trace[348265253] linearizableReadLoop","detail":"{readStateIndex:26961; appliedIndex:26956; }","duration":"1.150932545s","start":"2026-10-09T10:06:33.932246Z","end":"2026-10-09T10:06:35.083178Z","steps":["trace[348265253] 'read index received' (duration: 531.208304ms)","trace[348265253] 'applied index is now lower than readState.Index' (duration: 619.722481ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:35.083235Z","caller":"traceutil/trace.go:171","msg":"trace[1188948236] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"684.697882ms","start":"2026-10-09T10:06:34.398505Z","end":"2026-10-09T10:06:35.083203Z","steps":["trace[1188948236] 'process raft request' (duration: 684.400515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:35.083422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.398489Z","time spent":"684.821634ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:35.114534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153216155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-09T10:06:35.114623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186967839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:35.114634Z","caller":"traceutil/trace.go:171","msg":"trace[1194020116] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:26101; }","duration":"1.153366878s","start":"2026-10-09T10:06:33.961248Z","end":"2026-10-09T10:06:35.114615Z","steps":["trace[1194020116] 'agreement among raft nodes before linearized reading' (duration: 1.122227423s)","trace[1194020116] 'count revisions from in-memory index tree' (duration: 30.965551ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:35.11472Z","caller":"traceutil/trace.go:171","msg":"trace[975155909] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:26101; }","duration":"1.187170374s","start":"2026-10-09T10:06:33.927524Z","end":"2026-10-09T10:06:35.114694Z","steps":["trace[975155909] 'agreement among raft nodes before linearized reading' (duration: 1.155839064s)","trace[975155909] 'count revisions from in-memory index tree' (duration: 31.187246ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.114737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.961234Z","time spent":"1.153488921s","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:35.114764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.927489Z","time spent":"1.187265095s","remote":"127.0.0.1:35678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:35.114731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.795709416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:35.114888Z","caller":"traceutil/trace.go:171","msg":"trace[916379335] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26101; }","duration":"1.795958631s","start":"2026-10-09T10:06:33.318886Z","end":"2026-10-09T10:06:35.114845Z","steps":["trace[916379335] 'agreement among raft nodes before linearized reading' (duration: 1.76455972s)","trace[916379335] 'count revisions from in-memory index tree' (duration: 31.117645ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.114959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.318864Z","time spent":"1.796080423s","remote":"127.0.0.1:56192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:35.114937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78026559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19150"} {"level":"warn","ts":"2026-10-09T10:06:35.114982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.450477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11716"} {"level":"info","ts":"2026-10-09T10:06:35.11504Z","caller":"traceutil/trace.go:171","msg":"trace[1706218064] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26101; }","duration":"1.780456675s","start":"2026-10-09T10:06:33.334553Z","end":"2026-10-09T10:06:35.115009Z","steps":["trace[1706218064] 'agreement among raft nodes before linearized reading' (duration: 1.748874839s)","trace[1706218064] 'range keys from in-memory index tree' (duration: 31.36757ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:35.115054Z","caller":"traceutil/trace.go:171","msg":"trace[1475256006] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26101; }","duration":"560.595291ms","start":"2026-10-09T10:06:34.554441Z","end":"2026-10-09T10:06:35.115036Z","steps":["trace[1475256006] 'agreement among raft nodes before linearized reading' (duration: 529.103847ms)","trace[1475256006] 'range keys from in-memory index tree' (duration: 31.353641ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.11509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.554414Z","time spent":"560.668112ms","remote":"127.0.0.1:35962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11739,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-09T10:06:35.115091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.334531Z","time spent":"1.780551347s","remote":"127.0.0.1:35706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19173,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-10-09T10:06:35.115142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420195079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T10:06:35.115229Z","caller":"traceutil/trace.go:171","msg":"trace[1014417768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26101; }","duration":"1.420467695s","start":"2026-10-09T10:06:33.69474Z","end":"2026-10-09T10:06:35.115208Z","steps":["trace[1014417768] 'agreement among raft nodes before linearized reading' (duration: 1.388640744s)","trace[1014417768] 'range keys from in-memory index tree' (duration: 31.497063ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.115257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751983259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T10:06:35.115313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.122322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"warn","ts":"2026-10-09T10:06:35.115313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.061535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T10:06:35.115353Z","caller":"traceutil/trace.go:171","msg":"trace[99499613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26101; }","duration":"1.752841389s","start":"2026-10-09T10:06:33.362485Z","end":"2026-10-09T10:06:35.115327Z","steps":["trace[99499613] 'agreement among raft nodes before linearized reading' (duration: 1.720905005s)","trace[99499613] 'range keys from in-memory index tree' (duration: 31.071924ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.115297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.6947Z","time spent":"1.420584267s","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-09T10:06:35.115396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.782099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-09T10:06:35.115297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.519477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-09T10:06:35.115446Z","caller":"traceutil/trace.go:171","msg":"trace[507610866] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26101; }","duration":"626.83028ms","start":"2026-10-09T10:06:34.488604Z","end":"2026-10-09T10:06:35.115434Z","steps":["trace[507610866] 'agreement among raft nodes before linearized reading' (duration: 594.951598ms)","trace[507610866] 'count revisions from in-memory index tree' (duration: 31.820911ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:35.115461Z","caller":"traceutil/trace.go:171","msg":"trace[642272861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26101; }","duration":"863.69878ms","start":"2026-10-09T10:06:34.251742Z","end":"2026-10-09T10:06:35.115441Z","steps":["trace[642272861] 'agreement among raft nodes before linearized reading' (duration: 831.876959ms)","trace[642272861] 'range keys from in-memory index tree' (duration: 31.620397ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.115482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.251723Z","time spent":"863.752862ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-09T10:06:35.115502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.737501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T10:06:35.115352Z","caller":"traceutil/trace.go:171","msg":"trace[499207078] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:26101; }","duration":"642.159093ms","start":"2026-10-09T10:06:34.473177Z","end":"2026-10-09T10:06:35.115337Z","steps":["trace[499207078] 'agreement among raft nodes before linearized reading' (duration: 610.417343ms)","trace[499207078] 'range keys from in-memory index tree' (duration: 31.652797ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.115337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09696256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:35.11557Z","caller":"traceutil/trace.go:171","msg":"trace[1045905202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26101; }","duration":"726.843484ms","start":"2026-10-09T10:06:34.388715Z","end":"2026-10-09T10:06:35.115558Z","steps":["trace[1045905202] 'agreement among raft nodes before linearized reading' (duration: 694.88861ms)","trace[1045905202] 'range keys from in-memory index tree' (duration: 31.806301ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.115597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.473152Z","time spent":"642.425258ms","remote":"127.0.0.1:35692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-10-09T10:06:35.115479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.488554Z","time spent":"626.917302ms","remote":"127.0.0.1:56330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:35.115635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.388693Z","time spent":"726.931215ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-09T10:06:35.1156Z","caller":"traceutil/trace.go:171","msg":"trace[1353339041] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:26101; }","duration":"1.097273607s","start":"2026-10-09T10:06:34.018312Z","end":"2026-10-09T10:06:35.115585Z","steps":["trace[1353339041] 'agreement among raft nodes before linearized reading' (duration: 1.065319472s)","trace[1353339041] 'count revisions from in-memory index tree' (duration: 31.686909ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.115412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.36246Z","time spent":"1.75293941s","remote":"127.0.0.1:35534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T10:06:35.115823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.018282Z","time spent":"1.097526792s","remote":"127.0.0.1:46144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"info","ts":"2026-10-09T10:06:35.11539Z","caller":"traceutil/trace.go:171","msg":"trace[214078499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26101; }","duration":"790.139087ms","start":"2026-10-09T10:06:34.325237Z","end":"2026-10-09T10:06:35.115376Z","steps":["trace[214078499] 'agreement among raft nodes before linearized reading' (duration: 758.374267ms)","trace[214078499] 'range keys from in-memory index tree' (duration: 31.554715ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.115985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:34.325166Z","time spent":"790.807632ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T10:06:35.115952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.961253904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:35.116358Z","caller":"traceutil/trace.go:171","msg":"trace[1660842719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:26101; }","duration":"1.962443139s","start":"2026-10-09T10:06:33.153774Z","end":"2026-10-09T10:06:35.116217Z","steps":["trace[1660842719] 'agreement among raft nodes before linearized reading' (duration: 1.929680117s)","trace[1660842719] 'count revisions from in-memory index tree' (duration: 31.531464ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.11793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.789124639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-10-09T10:06:35.117638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.153753Z","time spent":"1.962668765s","remote":"127.0.0.1:51446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-10-09T10:06:35.11801Z","caller":"traceutil/trace.go:171","msg":"trace[2109140740] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26101; }","duration":"1.789258112s","start":"2026-10-09T10:06:33.328731Z","end":"2026-10-09T10:06:35.117989Z","steps":["trace[2109140740] 'agreement among raft nodes before linearized reading' (duration: 1.754705229s)","trace[2109140740] 'count revisions from in-memory index tree' (duration: 34.44805ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:35.118049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:33.328703Z","time spent":"1.789333054s","remote":"127.0.0.1:55786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":163,"response size":32,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:39.169695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.081401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:39.169802Z","caller":"traceutil/trace.go:171","msg":"trace[1094326226] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"294.777533ms","start":"2026-10-09T10:06:38.875003Z","end":"2026-10-09T10:06:39.16978Z","steps":["trace[1094326226] 'process raft request' (duration: 136.493027ms)","trace[1094326226] 'compare' (duration: 157.014577ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:39.44892Z","caller":"traceutil/trace.go:171","msg":"trace[483060001] linearizableReadLoop","detail":"{readStateIndex:26992; appliedIndex:26989; }","duration":"276.853424ms","start":"2026-10-09T10:06:39.172031Z","end":"2026-10-09T10:06:39.448885Z","steps":["trace[483060001] 'read index received' (duration: 248.092161ms)","trace[483060001] 'applied index is now lower than readState.Index' (duration: 28.759593ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:39.448976Z","caller":"traceutil/trace.go:171","msg":"trace[1945843496] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"350.595731ms","start":"2026-10-09T10:06:39.098346Z","end":"2026-10-09T10:06:39.448942Z","steps":["trace[1945843496] 'process raft request' (duration: 350.430297ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:39.449008Z","caller":"traceutil/trace.go:171","msg":"trace[423662498] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"315.069438ms","start":"2026-10-09T10:06:39.133899Z","end":"2026-10-09T10:06:39.448968Z","steps":["trace[423662498] 'process raft request' (duration: 314.938295ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:39.448993Z","caller":"traceutil/trace.go:171","msg":"trace[1643474468] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"570.655455ms","start":"2026-10-09T10:06:38.878284Z","end":"2026-10-09T10:06:39.448939Z","steps":["trace[1643474468] 'process raft request' (duration: 541.82919ms)","trace[1643474468] 'compare' (duration: 28.374864ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:39.449138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:39.098315Z","time spent":"350.733124ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:39.449197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:39.13387Z","time spent":"315.19715ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:39.44924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.197821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-10-09T10:06:39.449261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:38.878264Z","time spent":"570.84048ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:39.449281Z","caller":"traceutil/trace.go:171","msg":"trace[421622524] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26131; }","duration":"277.269203ms","start":"2026-10-09T10:06:39.171997Z","end":"2026-10-09T10:06:39.449266Z","steps":["trace[421622524] 'agreement among raft nodes before linearized reading' (duration: 277.079109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:39.44948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.829234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-10-09T10:06:39.449528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.375655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:06:39.449562Z","caller":"traceutil/trace.go:171","msg":"trace[1940592446] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:26131; }","duration":"225.457746ms","start":"2026-10-09T10:06:39.224092Z","end":"2026-10-09T10:06:39.449549Z","steps":["trace[1940592446] 'agreement among raft nodes before linearized reading' (duration: 225.279393ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:39.449571Z","caller":"traceutil/trace.go:171","msg":"trace[1772801171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26131; }","duration":"268.957657ms","start":"2026-10-09T10:06:39.180585Z","end":"2026-10-09T10:06:39.449543Z","steps":["trace[1772801171] 'agreement among raft nodes before linearized reading' (duration: 268.725692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:39.449731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.143022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T10:06:39.449776Z","caller":"traceutil/trace.go:171","msg":"trace[1481112028] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:26131; }","duration":"124.214874ms","start":"2026-10-09T10:06:39.325546Z","end":"2026-10-09T10:06:39.449761Z","steps":["trace[1481112028] 'agreement among raft nodes before linearized reading' (duration: 123.972289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:39.449816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.314762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:39.449881Z","caller":"traceutil/trace.go:171","msg":"trace[1676266195] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:26131; }","duration":"165.418824ms","start":"2026-10-09T10:06:39.284439Z","end":"2026-10-09T10:06:39.449858Z","steps":["trace[1676266195] 'agreement among raft nodes before linearized reading' (duration: 165.287161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:39.449904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.072065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-09T10:06:39.449832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.244806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-09T10:06:39.449939Z","caller":"traceutil/trace.go:171","msg":"trace[773135824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26131; }","duration":"267.147117ms","start":"2026-10-09T10:06:39.182781Z","end":"2026-10-09T10:06:39.449928Z","steps":["trace[773135824] 'agreement among raft nodes before linearized reading' (duration: 267.068475ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:39.449999Z","caller":"traceutil/trace.go:171","msg":"trace[1565845308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26131; }","duration":"266.427721ms","start":"2026-10-09T10:06:39.183551Z","end":"2026-10-09T10:06:39.449979Z","steps":["trace[1565845308] 'agreement among raft nodes before linearized reading' (duration: 266.206976ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:39.450163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.406302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-09T10:06:39.450194Z","caller":"traceutil/trace.go:171","msg":"trace[329367010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26131; }","duration":"267.491714ms","start":"2026-10-09T10:06:39.182694Z","end":"2026-10-09T10:06:39.450185Z","steps":["trace[329367010] 'agreement among raft nodes before linearized reading' (duration: 267.408283ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:39.727263Z","caller":"traceutil/trace.go:171","msg":"trace[832547412] linearizableReadLoop","detail":"{readStateIndex:26996; appliedIndex:26993; }","duration":"194.946554ms","start":"2026-10-09T10:06:39.532284Z","end":"2026-10-09T10:06:39.727231Z","steps":["trace[832547412] 'read index received' (duration: 184.701755ms)","trace[832547412] 'applied index is now lower than readState.Index' (duration: 10.243199ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:39.727309Z","caller":"traceutil/trace.go:171","msg":"trace[987444101] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"272.964926ms","start":"2026-10-09T10:06:39.454288Z","end":"2026-10-09T10:06:39.727253Z","steps":["trace[987444101] 'process raft request' (duration: 262.656896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:39.727562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.238811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:06:39.72761Z","caller":"traceutil/trace.go:171","msg":"trace[79190664] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:26135; }","duration":"195.373004ms","start":"2026-10-09T10:06:39.532225Z","end":"2026-10-09T10:06:39.727598Z","steps":["trace[79190664] 'agreement among raft nodes before linearized reading' (duration: 195.131129ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:39.727806Z","caller":"traceutil/trace.go:171","msg":"trace[1254774120] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"272.879284ms","start":"2026-10-09T10:06:39.454886Z","end":"2026-10-09T10:06:39.727765Z","steps":["trace[1254774120] 'process raft request' (duration: 272.229589ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:39.727854Z","caller":"traceutil/trace.go:171","msg":"trace[1016593377] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"272.337602ms","start":"2026-10-09T10:06:39.455464Z","end":"2026-10-09T10:06:39.727802Z","steps":["trace[1016593377] 'process raft request' (duration: 271.700468ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:40.937556Z","caller":"traceutil/trace.go:171","msg":"trace[1991980289] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"519.149845ms","start":"2026-10-09T10:06:40.418382Z","end":"2026-10-09T10:06:40.937532Z","steps":["trace[1991980289] 'process raft request' (duration: 518.981001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:40.937738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:40.418332Z","time spent":"519.300458ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:40.98779Z","caller":"traceutil/trace.go:171","msg":"trace[596623323] linearizableReadLoop","detail":"{readStateIndex:27003; appliedIndex:27002; }","duration":"381.266355ms","start":"2026-10-09T10:06:40.606479Z","end":"2026-10-09T10:06:40.987746Z","steps":["trace[596623323] 'read index received' (duration: 331.123035ms)","trace[596623323] 'applied index is now lower than readState.Index' (duration: 50.14148ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:40.987853Z","caller":"traceutil/trace.go:171","msg":"trace[1728696689] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"499.504556ms","start":"2026-10-09T10:06:40.488315Z","end":"2026-10-09T10:06:40.987819Z","steps":["trace[1728696689] 'process raft request' (duration: 499.180929ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:40.98799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.50072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:40.988083Z","caller":"traceutil/trace.go:171","msg":"trace[103696895] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:26142; }","duration":"381.646183ms","start":"2026-10-09T10:06:40.606413Z","end":"2026-10-09T10:06:40.988059Z","steps":["trace[103696895] 'agreement among raft nodes before linearized reading' (duration: 381.50679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:40.988127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:40.60638Z","time spent":"381.736995ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:40.988523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.434158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-09T10:06:40.988563Z","caller":"traceutil/trace.go:171","msg":"trace[420587342] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26142; }","duration":"278.51942ms","start":"2026-10-09T10:06:40.710034Z","end":"2026-10-09T10:06:40.988553Z","steps":["trace[420587342] 'agreement among raft nodes before linearized reading' (duration: 278.405237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:40.988658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.811114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:40.988767Z","caller":"traceutil/trace.go:171","msg":"trace[510924654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26142; }","duration":"110.996908ms","start":"2026-10-09T10:06:40.877746Z","end":"2026-10-09T10:06:40.988743Z","steps":["trace[510924654] 'agreement among raft nodes before linearized reading' (duration: 110.768303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:40.988778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.052327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:40.988811Z","caller":"traceutil/trace.go:171","msg":"trace[1593677050] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:26142; }","duration":"161.125077ms","start":"2026-10-09T10:06:40.827676Z","end":"2026-10-09T10:06:40.988801Z","steps":["trace[1593677050] 'agreement among raft nodes before linearized reading' (duration: 161.062276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:40.98926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:40.488292Z","time spent":"500.784343ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:41.677663Z","caller":"traceutil/trace.go:171","msg":"trace[1254778864] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"220.075915ms","start":"2026-10-09T10:06:41.457498Z","end":"2026-10-09T10:06:41.677574Z","steps":["trace[1254778864] 'process raft request' (duration: 207.885843ms)","trace[1254778864] 'compare' (duration: 11.398434ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:41.677707Z","caller":"traceutil/trace.go:171","msg":"trace[1966369300] linearizableReadLoop","detail":"{readStateIndex:27007; appliedIndex:27005; }","duration":"211.471013ms","start":"2026-10-09T10:06:41.466193Z","end":"2026-10-09T10:06:41.677664Z","steps":["trace[1966369300] 'read index received' (duration: 31.33046ms)","trace[1966369300] 'applied index is now lower than readState.Index' (duration: 180.119962ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:41.677737Z","caller":"traceutil/trace.go:171","msg":"trace[1909276649] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"216.371332ms","start":"2026-10-09T10:06:41.46132Z","end":"2026-10-09T10:06:41.677691Z","steps":["trace[1909276649] 'process raft request' (duration: 216.098106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:41.678829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.448855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T10:06:41.679318Z","caller":"traceutil/trace.go:171","msg":"trace[2068000750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26147; }","duration":"213.149391ms","start":"2026-10-09T10:06:41.466142Z","end":"2026-10-09T10:06:41.679291Z","steps":["trace[2068000750] 'agreement among raft nodes before linearized reading' (duration: 211.938133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:42.015834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.686506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:42.015935Z","caller":"traceutil/trace.go:171","msg":"trace[1074471259] linearizableReadLoop","detail":"{readStateIndex:27009; appliedIndex:27008; }","duration":"282.486439ms","start":"2026-10-09T10:06:41.733427Z","end":"2026-10-09T10:06:42.015914Z","steps":["trace[1074471259] 'read index received' (duration: 84.560929ms)","trace[1074471259] 'applied index is now lower than readState.Index' (duration: 197.92434ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:42.016098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.676613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-09T10:06:42.016132Z","caller":"traceutil/trace.go:171","msg":"trace[717179306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26148; }","duration":"282.784326ms","start":"2026-10-09T10:06:41.733337Z","end":"2026-10-09T10:06:42.016121Z","steps":["trace[717179306] 'agreement among raft nodes before linearized reading' (duration: 282.648893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:42.016114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.688873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-09T10:06:42.016182Z","caller":"traceutil/trace.go:171","msg":"trace[381992738] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26148; }","duration":"282.854017ms","start":"2026-10-09T10:06:41.733311Z","end":"2026-10-09T10:06:42.016165Z","steps":["trace[381992738] 'agreement among raft nodes before linearized reading' (duration: 282.671723ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:42.01617Z","caller":"traceutil/trace.go:171","msg":"trace[1763464695] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"329.276234ms","start":"2026-10-09T10:06:41.686858Z","end":"2026-10-09T10:06:42.016134Z","steps":["trace[1763464695] 'process raft request' (duration: 131.20418ms)","trace[1763464695] 'compare' (duration: 197.140344ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:42.016187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.753625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-10-09T10:06:42.016329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:41.68682Z","time spent":"329.425798ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:42.016358Z","caller":"traceutil/trace.go:171","msg":"trace[633749767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26148; }","duration":"282.97829ms","start":"2026-10-09T10:06:41.73336Z","end":"2026-10-09T10:06:42.016338Z","steps":["trace[633749767] 'agreement among raft nodes before linearized reading' (duration: 282.644362ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:42.01759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.001857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:42.017672Z","caller":"traceutil/trace.go:171","msg":"trace[1833407447] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26149; }","duration":"226.11064ms","start":"2026-10-09T10:06:41.791523Z","end":"2026-10-09T10:06:42.017633Z","steps":["trace[1833407447] 'agreement among raft nodes before linearized reading' (duration: 225.999527ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:42.369543Z","caller":"traceutil/trace.go:171","msg":"trace[1509418989] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"345.715761ms","start":"2026-10-09T10:06:42.023798Z","end":"2026-10-09T10:06:42.369514Z","steps":["trace[1509418989] 'process raft request' (duration: 345.590668ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:42.369607Z","caller":"traceutil/trace.go:171","msg":"trace[1295415484] transaction","detail":"{read_only:false; response_revision:26151; number_of_response:1; }","duration":"345.745651ms","start":"2026-10-09T10:06:42.0238Z","end":"2026-10-09T10:06:42.369545Z","steps":["trace[1295415484] 'process raft request' (duration: 345.383583ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:42.369748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:42.023773Z","time spent":"345.855284ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:42.36989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:42.023773Z","time spent":"345.962916ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:42.369881Z","caller":"traceutil/trace.go:171","msg":"trace[276714203] transaction","detail":"{read_only:false; response_revision:26153; number_of_response:1; }","duration":"299.510219ms","start":"2026-10-09T10:06:42.070341Z","end":"2026-10-09T10:06:42.369851Z","steps":["trace[276714203] 'process raft request' (duration: 299.10446ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:42.370448Z","caller":"traceutil/trace.go:171","msg":"trace[702742584] transaction","detail":"{read_only:false; response_revision:26154; number_of_response:1; }","duration":"298.741092ms","start":"2026-10-09T10:06:42.071684Z","end":"2026-10-09T10:06:42.370425Z","steps":["trace[702742584] 'process raft request' (duration: 298.103958ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:42.370662Z","caller":"traceutil/trace.go:171","msg":"trace[148689955] transaction","detail":"{read_only:false; response_revision:26155; number_of_response:1; }","duration":"298.893116ms","start":"2026-10-09T10:06:42.07174Z","end":"2026-10-09T10:06:42.370633Z","steps":["trace[148689955] 'process raft request' (duration: 298.549928ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:42.370639Z","caller":"traceutil/trace.go:171","msg":"trace[556771757] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"297.892704ms","start":"2026-10-09T10:06:42.072718Z","end":"2026-10-09T10:06:42.370611Z","steps":["trace[556771757] 'process raft request' (duration: 297.694468ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:42.37067Z","caller":"traceutil/trace.go:171","msg":"trace[1315582660] linearizableReadLoop","detail":"{readStateIndex:27013; appliedIndex:27010; }","duration":"346.629312ms","start":"2026-10-09T10:06:42.023941Z","end":"2026-10-09T10:06:42.37057Z","steps":["trace[1315582660] 'read index received' (duration: 57.223948ms)","trace[1315582660] 'applied index is now lower than readState.Index' (duration: 289.403974ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:42.370741Z","caller":"traceutil/trace.go:171","msg":"trace[1024077798] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"297.860484ms","start":"2026-10-09T10:06:42.072838Z","end":"2026-10-09T10:06:42.370699Z","steps":["trace[1024077798] 'process raft request' (duration: 297.650458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:42.371198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.221885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:42.371254Z","caller":"traceutil/trace.go:171","msg":"trace[1427456484] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26157; }","duration":"347.325677ms","start":"2026-10-09T10:06:42.023909Z","end":"2026-10-09T10:06:42.371235Z","steps":["trace[1427456484] 'agreement among raft nodes before linearized reading' (duration: 347.069932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:42.371294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:42.023896Z","time spent":"347.386998ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-10-09T10:06:42.390312Z","caller":"traceutil/trace.go:171","msg":"trace[1297613164] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"267.825472ms","start":"2026-10-09T10:06:42.122447Z","end":"2026-10-09T10:06:42.390272Z","steps":["trace[1297613164] 'process raft request' (duration: 267.531764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:42.391145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.239519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:42.391265Z","caller":"traceutil/trace.go:171","msg":"trace[956023504] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26158; }","duration":"220.430354ms","start":"2026-10-09T10:06:42.170804Z","end":"2026-10-09T10:06:42.391234Z","steps":["trace[956023504] 'agreement among raft nodes before linearized reading' (duration: 219.641967ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:44.010091Z","caller":"traceutil/trace.go:171","msg":"trace[1175500732] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"324.453207ms","start":"2026-10-09T10:06:43.685605Z","end":"2026-10-09T10:06:44.010058Z","steps":["trace[1175500732] 'process raft request' (duration: 324.274893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.010263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:43.685583Z","time spent":"324.590169ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:44.199326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.38292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:44.199724Z","caller":"traceutil/trace.go:171","msg":"trace[1069038156] linearizableReadLoop","detail":"{readStateIndex:27024; appliedIndex:27022; }","duration":"348.413781ms","start":"2026-10-09T10:06:43.851292Z","end":"2026-10-09T10:06:44.199706Z","steps":["trace[1069038156] 'read index received' (duration: 158.643813ms)","trace[1069038156] 'applied index is now lower than readState.Index' (duration: 189.768618ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:44.199844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.552243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:44.199879Z","caller":"traceutil/trace.go:171","msg":"trace[120589753] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:26163; }","duration":"348.639867ms","start":"2026-10-09T10:06:43.851227Z","end":"2026-10-09T10:06:44.199867Z","steps":["trace[120589753] 'agreement among raft nodes before linearized reading' (duration: 348.552544ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:44.199846Z","caller":"traceutil/trace.go:171","msg":"trace[1785097433] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"453.355514ms","start":"2026-10-09T10:06:43.746453Z","end":"2026-10-09T10:06:44.199809Z","steps":["trace[1785097433] 'process raft request' (duration: 344.390111ms)","trace[1785097433] 'compare' (duration: 108.037753ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:44.199915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:43.851202Z","time spent":"348.703318ms","remote":"127.0.0.1:56120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:44.20002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:43.746421Z","time spent":"453.507799ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:44.213053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.042759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:44.213134Z","caller":"traceutil/trace.go:171","msg":"trace[890475664] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:26163; }","duration":"222.198912ms","start":"2026-10-09T10:06:43.990916Z","end":"2026-10-09T10:06:44.213115Z","steps":["trace[890475664] 'agreement among raft nodes before linearized reading' (duration: 222.043499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.213483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.13138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-09T10:06:44.213593Z","caller":"traceutil/trace.go:171","msg":"trace[2006106752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26163; }","duration":"128.333185ms","start":"2026-10-09T10:06:44.08523Z","end":"2026-10-09T10:06:44.213563Z","steps":["trace[2006106752] 'agreement among raft nodes before linearized reading' (duration: 127.957587ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.213569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.411222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T10:06:44.213748Z","caller":"traceutil/trace.go:171","msg":"trace[1449632917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26163; }","duration":"190.645957ms","start":"2026-10-09T10:06:44.023064Z","end":"2026-10-09T10:06:44.21371Z","steps":["trace[1449632917] 'agreement among raft nodes before linearized reading' (duration: 190.182857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.413317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.700473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:44.414053Z","caller":"traceutil/trace.go:171","msg":"trace[1973168862] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"195.173059ms","start":"2026-10-09T10:06:44.218765Z","end":"2026-10-09T10:06:44.413938Z","steps":["trace[1973168862] 'process raft request' (duration: 87.708139ms)","trace[1973168862] 'compare' (duration: 106.13799ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:44.720286Z","caller":"traceutil/trace.go:171","msg":"trace[1632407790] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"342.493639ms","start":"2026-10-09T10:06:44.377754Z","end":"2026-10-09T10:06:44.720248Z","steps":["trace[1632407790] 'process raft request' (duration: 306.395572ms)","trace[1632407790] 'compare' (duration: 35.80143ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:44.720441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:44.377735Z","time spent":"342.618172ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:44.720824Z","caller":"traceutil/trace.go:171","msg":"trace[1492845967] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"338.675103ms","start":"2026-10-09T10:06:44.382131Z","end":"2026-10-09T10:06:44.720806Z","steps":["trace[1492845967] 'process raft request' (duration: 338.303285ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.720894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:44.382116Z","time spent":"338.740084ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:44.720924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.286057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:44.720967Z","caller":"traceutil/trace.go:171","msg":"trace[1517273725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26170; }","duration":"360.385089ms","start":"2026-10-09T10:06:44.360572Z","end":"2026-10-09T10:06:44.720957Z","steps":["trace[1517273725] 'agreement among raft nodes before linearized reading' (duration: 360.279576ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:44.721136Z","caller":"traceutil/trace.go:171","msg":"trace[1038351162] transaction","detail":"{read_only:false; response_revision:26168; number_of_response:1; }","duration":"342.700965ms","start":"2026-10-09T10:06:44.378423Z","end":"2026-10-09T10:06:44.721124Z","steps":["trace[1038351162] 'process raft request' (duration: 341.734523ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.72119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:44.378388Z","time spent":"342.772006ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:44.721255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:44.360541Z","time spent":"360.447151ms","remote":"127.0.0.1:35544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T10:06:44.720806Z","caller":"traceutil/trace.go:171","msg":"trace[645992339] linearizableReadLoop","detail":"{readStateIndex:27032; appliedIndex:27028; }","duration":"307.142739ms","start":"2026-10-09T10:06:44.41364Z","end":"2026-10-09T10:06:44.720782Z","steps":["trace[645992339] 'read index received' (duration: 270.404589ms)","trace[645992339] 'applied index is now lower than readState.Index' (duration: 36.73721ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:44.721489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.023914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:44.721538Z","caller":"traceutil/trace.go:171","msg":"trace[147105167] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26170; }","duration":"237.100875ms","start":"2026-10-09T10:06:44.484412Z","end":"2026-10-09T10:06:44.721513Z","steps":["trace[147105167] 'agreement among raft nodes before linearized reading' (duration: 237.035214ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:44.721986Z","caller":"traceutil/trace.go:171","msg":"trace[813018343] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"339.503342ms","start":"2026-10-09T10:06:44.382455Z","end":"2026-10-09T10:06:44.721958Z","steps":["trace[813018343] 'process raft request' (duration: 338.238473ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.722053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.46496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T10:06:44.722121Z","caller":"traceutil/trace.go:171","msg":"trace[996291609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26170; }","duration":"347.583982ms","start":"2026-10-09T10:06:44.374518Z","end":"2026-10-09T10:06:44.722102Z","steps":["trace[996291609] 'agreement among raft nodes before linearized reading' (duration: 347.389528ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.722122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:44.382435Z","time spent":"339.612684ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:44.722161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:44.374496Z","time spent":"347.655474ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-09T10:06:44.722237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.450488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-10-09T10:06:44.722306Z","caller":"traceutil/trace.go:171","msg":"trace[1046108679] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:26170; }","duration":"205.56623ms","start":"2026-10-09T10:06:44.516721Z","end":"2026-10-09T10:06:44.722287Z","steps":["trace[1046108679] 'agreement among raft nodes before linearized reading' (duration: 205.355516ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.722332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.614143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T10:06:44.722376Z","caller":"traceutil/trace.go:171","msg":"trace[2126636088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26170; }","duration":"347.701595ms","start":"2026-10-09T10:06:44.374662Z","end":"2026-10-09T10:06:44.722364Z","steps":["trace[2126636088] 'agreement among raft nodes before linearized reading' (duration: 347.059501ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:44.722405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:44.374614Z","time spent":"347.784477ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T10:06:55.165512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.467684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:55.165748Z","caller":"traceutil/trace.go:171","msg":"trace[1444748932] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"404.938415ms","start":"2026-10-09T10:06:54.760775Z","end":"2026-10-09T10:06:55.165714Z","steps":["trace[1444748932] 'process raft request' (duration: 137.186414ms)","trace[1444748932] 'compare' (duration: 266.585895ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:55.165908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:54.76076Z","time spent":"405.061727ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:55.165935Z","caller":"traceutil/trace.go:171","msg":"trace[1141921138] linearizableReadLoop","detail":"{readStateIndex:27106; appliedIndex:27103; }","duration":"357.209278ms","start":"2026-10-09T10:06:54.808691Z","end":"2026-10-09T10:06:55.165901Z","steps":["trace[1141921138] 'read index received' (duration: 30.782308ms)","trace[1141921138] 'applied index is now lower than readState.Index' (duration: 326.4257ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:55.165941Z","caller":"traceutil/trace.go:171","msg":"trace[1204909558] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"400.577076ms","start":"2026-10-09T10:06:54.765337Z","end":"2026-10-09T10:06:55.165914Z","steps":["trace[1204909558] 'process raft request' (duration: 400.380312ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:55.165772Z","caller":"traceutil/trace.go:171","msg":"trace[2114834670] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"400.484795ms","start":"2026-10-09T10:06:54.765269Z","end":"2026-10-09T10:06:55.165754Z","steps":["trace[2114834670] 'process raft request' (duration: 400.339601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:55.166062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:54.765258Z","time spent":"400.773821ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:55.16608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:54.76533Z","time spent":"400.686239ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:55.166161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.447693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T10:06:55.16622Z","caller":"traceutil/trace.go:171","msg":"trace[1905435470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26242; }","duration":"357.524324ms","start":"2026-10-09T10:06:54.808667Z","end":"2026-10-09T10:06:55.166192Z","steps":["trace[1905435470] 'agreement among raft nodes before linearized reading' (duration: 357.359931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:55.16626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:54.80864Z","time spent":"357.609636ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-09T10:06:55.263409Z","caller":"traceutil/trace.go:171","msg":"trace[1385663633] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"187.850595ms","start":"2026-10-09T10:06:55.075538Z","end":"2026-10-09T10:06:55.263389Z","steps":["trace[1385663633] 'process raft request' (duration: 187.721122ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:55.263573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.849466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T10:06:55.263633Z","caller":"traceutil/trace.go:171","msg":"trace[667273730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26243; }","duration":"444.931107ms","start":"2026-10-09T10:06:54.818687Z","end":"2026-10-09T10:06:55.263618Z","steps":["trace[667273730] 'agreement among raft nodes before linearized reading' (duration: 444.746113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:55.263691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:54.818677Z","time spent":"445.005789ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T10:06:55.631874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.572685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:55.631977Z","caller":"traceutil/trace.go:171","msg":"trace[1297779378] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"458.881318ms","start":"2026-10-09T10:06:55.173075Z","end":"2026-10-09T10:06:55.631956Z","steps":["trace[1297779378] 'process raft request' (duration: 294.13011ms)","trace[1297779378] 'compare' (duration: 164.343599ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:55.632037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:55.173054Z","time spent":"458.950489ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:55.758026Z","caller":"traceutil/trace.go:171","msg":"trace[586123195] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"130.170407ms","start":"2026-10-09T10:06:55.627829Z","end":"2026-10-09T10:06:55.758Z","steps":["trace[586123195] 'process raft request' (duration: 130.120806ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:55.758128Z","caller":"traceutil/trace.go:171","msg":"trace[436875094] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"489.448851ms","start":"2026-10-09T10:06:55.268612Z","end":"2026-10-09T10:06:55.758061Z","steps":["trace[436875094] 'process raft request' (duration: 472.348259ms)","trace[436875094] 'compare' (duration: 16.834936ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:55.758178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.608639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:55.758263Z","caller":"traceutil/trace.go:171","msg":"trace[1264640750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26246; }","duration":"397.743794ms","start":"2026-10-09T10:06:55.360498Z","end":"2026-10-09T10:06:55.758242Z","steps":["trace[1264640750] 'agreement among raft nodes before linearized reading' (duration: 397.57387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:55.758317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:55.360482Z","time spent":"397.827236ms","remote":"127.0.0.1:35534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-09T10:06:55.7583Z","caller":"traceutil/trace.go:171","msg":"trace[1428372460] linearizableReadLoop","detail":"{readStateIndex:27109; appliedIndex:27107; }","duration":"397.525438ms","start":"2026-10-09T10:06:55.360537Z","end":"2026-10-09T10:06:55.758062Z","steps":["trace[1428372460] 'read index received' (duration: 106.682243ms)","trace[1428372460] 'applied index is now lower than readState.Index' (duration: 290.839845ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:55.758344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:55.268591Z","time spent":"489.636384ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:55.759216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.43567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:06:55.75932Z","caller":"traceutil/trace.go:171","msg":"trace[433776314] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26246; }","duration":"150.592234ms","start":"2026-10-09T10:06:55.608709Z","end":"2026-10-09T10:06:55.759301Z","steps":["trace[433776314] 'agreement among raft nodes before linearized reading' (duration: 150.440831ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:55.759332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.82616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:06:55.759401Z","caller":"traceutil/trace.go:171","msg":"trace[351052304] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26246; }","duration":"202.936823ms","start":"2026-10-09T10:06:55.55645Z","end":"2026-10-09T10:06:55.759387Z","steps":["trace[351052304] 'agreement among raft nodes before linearized reading' (duration: 202.846591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:55.759271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.80581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:06:55.759691Z","caller":"traceutil/trace.go:171","msg":"trace[1394449388] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:26246; }","duration":"289.299111ms","start":"2026-10-09T10:06:55.470378Z","end":"2026-10-09T10:06:55.759677Z","steps":["trace[1394449388] 'agreement among raft nodes before linearized reading' (duration: 288.762449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:56.379405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.65024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:06:56.379478Z","caller":"traceutil/trace.go:171","msg":"trace[175061596] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:26246; }","duration":"508.787613ms","start":"2026-10-09T10:06:55.870673Z","end":"2026-10-09T10:06:56.379461Z","steps":["trace[175061596] 'count revisions from in-memory index tree' (duration: 508.507097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:56.379517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:55.870627Z","time spent":"508.880545ms","remote":"127.0.0.1:35970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":19,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:56.379622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.679532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:56.379757Z","caller":"traceutil/trace.go:171","msg":"trace[1170227570] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"395.340799ms","start":"2026-10-09T10:06:55.9844Z","end":"2026-10-09T10:06:56.379741Z","steps":["trace[1170227570] 'process raft request' (duration: 172.35569ms)","trace[1170227570] 'compare' (duration: 222.409087ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:56.379858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:55.984361Z","time spent":"395.458482ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:56.389112Z","caller":"traceutil/trace.go:171","msg":"trace[824072209] linearizableReadLoop","detail":"{readStateIndex:27112; appliedIndex:27111; }","duration":"123.10622ms","start":"2026-10-09T10:06:56.265979Z","end":"2026-10-09T10:06:56.389085Z","steps":["trace[824072209] 'read index received' (duration: 122.931646ms)","trace[824072209] 'applied index is now lower than readState.Index' (duration: 173.584µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:56.389201Z","caller":"traceutil/trace.go:171","msg":"trace[1188147190] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"292.481583ms","start":"2026-10-09T10:06:56.096691Z","end":"2026-10-09T10:06:56.389172Z","steps":["trace[1188147190] 'process raft request' (duration: 292.284008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:56.389512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.714194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-09T10:06:56.389571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.57357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-09T10:06:56.389732Z","caller":"traceutil/trace.go:171","msg":"trace[193045710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26248; }","duration":"123.666182ms","start":"2026-10-09T10:06:56.265967Z","end":"2026-10-09T10:06:56.389633Z","steps":["trace[193045710] 'agreement among raft nodes before linearized reading' (duration: 123.245742ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:56.38957Z","caller":"traceutil/trace.go:171","msg":"trace[1637900148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26248; }","duration":"102.817547ms","start":"2026-10-09T10:06:56.286736Z","end":"2026-10-09T10:06:56.389553Z","steps":["trace[1637900148] 'agreement among raft nodes before linearized reading' (duration: 102.49538ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:56.745132Z","caller":"traceutil/trace.go:171","msg":"trace[285817117] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"350.732213ms","start":"2026-10-09T10:06:56.39437Z","end":"2026-10-09T10:06:56.745102Z","steps":["trace[285817117] 'process raft request' (duration: 350.517328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:56.745344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:56.394337Z","time spent":"350.887296ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:57.171601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.276485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:06:57.171934Z","caller":"traceutil/trace.go:171","msg":"trace[714969005] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"315.980678ms","start":"2026-10-09T10:06:56.855933Z","end":"2026-10-09T10:06:57.171914Z","steps":["trace[714969005] 'process raft request' (duration: 315.941107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:57.172013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:56.855905Z","time spent":"316.070549ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:57.172213Z","caller":"traceutil/trace.go:171","msg":"trace[1026925229] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"714.996908ms","start":"2026-10-09T10:06:56.457166Z","end":"2026-10-09T10:06:57.172163Z","steps":["trace[1026925229] 'process raft request' (duration: 714.561209ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:57.172241Z","caller":"traceutil/trace.go:171","msg":"trace[911093514] linearizableReadLoop","detail":"{readStateIndex:27115; appliedIndex:27112; }","duration":"690.501131ms","start":"2026-10-09T10:06:56.481706Z","end":"2026-10-09T10:06:57.172208Z","steps":["trace[911093514] 'read index received' (duration: 263.173338ms)","trace[911093514] 'applied index is now lower than readState.Index' (duration: 427.324433ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:57.17256Z","caller":"traceutil/trace.go:171","msg":"trace[536130682] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"486.449044ms","start":"2026-10-09T10:06:56.686098Z","end":"2026-10-09T10:06:57.172547Z","steps":["trace[536130682] 'process raft request' (duration: 485.729178ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:57.172698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:56.686068Z","time spent":"486.532625ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:57.172562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:56.457143Z","time spent":"715.332516ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:57.172759Z","caller":"traceutil/trace.go:171","msg":"trace[1045433047] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"777.77477ms","start":"2026-10-09T10:06:56.394969Z","end":"2026-10-09T10:06:57.172743Z","steps":["trace[1045433047] 'process raft request' (duration: 494.257268ms)","trace[1045433047] 'compare' (duration: 281.925157ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:57.172829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:56.394948Z","time spent":"777.847631ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:06:57.172877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.167596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-09T10:06:57.172952Z","caller":"traceutil/trace.go:171","msg":"trace[370754255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26253; }","duration":"691.302759ms","start":"2026-10-09T10:06:56.48163Z","end":"2026-10-09T10:06:57.172933Z","steps":["trace[370754255] 'agreement among raft nodes before linearized reading' (duration: 691.095045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:57.173006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:56.48161Z","time spent":"691.382752ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-09T10:06:57.423686Z","caller":"traceutil/trace.go:171","msg":"trace[1667388122] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"388.913797ms","start":"2026-10-09T10:06:57.034709Z","end":"2026-10-09T10:06:57.423623Z","steps":["trace[1667388122] 'process raft request' (duration: 365.652438ms)","trace[1667388122] 'compare' (duration: 23.111605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:06:57.423853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:57.034683Z","time spent":"389.07913ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:06:57.42388Z","caller":"traceutil/trace.go:171","msg":"trace[452960842] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"153.450437ms","start":"2026-10-09T10:06:57.270407Z","end":"2026-10-09T10:06:57.423857Z","steps":["trace[452960842] 'process raft request' (duration: 153.407026ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:57.42403Z","caller":"traceutil/trace.go:171","msg":"trace[378588808] transaction","detail":"{read_only:false; response_revision:26256; number_of_response:1; }","duration":"249.644295ms","start":"2026-10-09T10:06:57.174364Z","end":"2026-10-09T10:06:57.424009Z","steps":["trace[378588808] 'process raft request' (duration: 249.326018ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:57.42406Z","caller":"traceutil/trace.go:171","msg":"trace[548653257] transaction","detail":"{read_only:false; response_revision:26257; number_of_response:1; }","duration":"248.543031ms","start":"2026-10-09T10:06:57.175481Z","end":"2026-10-09T10:06:57.424024Z","steps":["trace[548653257] 'process raft request' (duration: 248.247725ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:57.424142Z","caller":"traceutil/trace.go:171","msg":"trace[2121107386] linearizableReadLoop","detail":"{readStateIndex:27118; appliedIndex:27117; }","duration":"251.62718ms","start":"2026-10-09T10:06:57.172492Z","end":"2026-10-09T10:06:57.424119Z","steps":["trace[2121107386] 'read index received' (duration: 227.88745ms)","trace[2121107386] 'applied index is now lower than readState.Index' (duration: 23.73692ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:06:57.424175Z","caller":"traceutil/trace.go:171","msg":"trace[1236525542] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"250.099785ms","start":"2026-10-09T10:06:57.17405Z","end":"2026-10-09T10:06:57.42415Z","steps":["trace[1236525542] 'process raft request' (duration: 249.568694ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:57.424194Z","caller":"traceutil/trace.go:171","msg":"trace[149986085] transaction","detail":"{read_only:false; response_revision:26258; number_of_response:1; }","duration":"247.190191ms","start":"2026-10-09T10:06:57.1769Z","end":"2026-10-09T10:06:57.424091Z","steps":["trace[149986085] 'process raft request' (duration: 246.874934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:57.424362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.001334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-10-09T10:06:57.424458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.842817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T10:06:57.424514Z","caller":"traceutil/trace.go:171","msg":"trace[2056409063] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:26259; }","duration":"852.944089ms","start":"2026-10-09T10:06:56.571558Z","end":"2026-10-09T10:06:57.424503Z","steps":["trace[2056409063] 'agreement among raft nodes before linearized reading' (duration: 852.658203ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:06:57.424506Z","caller":"traceutil/trace.go:171","msg":"trace[1724930075] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26259; }","duration":"889.198909ms","start":"2026-10-09T10:06:56.535296Z","end":"2026-10-09T10:06:57.424495Z","steps":["trace[1724930075] 'agreement among raft nodes before linearized reading' (duration: 888.931113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:06:57.424546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:56.571537Z","time spent":"853.001471ms","remote":"127.0.0.1:35720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":240,"response size":32,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-10-09T10:06:57.424577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:06:56.535275Z","time spent":"889.291681ms","remote":"127.0.0.1:35714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-09T10:07:01.993309Z","caller":"traceutil/trace.go:171","msg":"trace[1453023057] transaction","detail":"{read_only:false; response_revision:26350; number_of_response:1; }","duration":"207.135536ms","start":"2026-10-09T10:07:01.786128Z","end":"2026-10-09T10:07:01.993263Z","steps":["trace[1453023057] 'process raft request' (duration: 177.431623ms)","trace[1453023057] 'compare' (duration: 29.473578ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:01.993539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.608546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T10:07:01.993599Z","caller":"traceutil/trace.go:171","msg":"trace[1924742829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26350; }","duration":"198.732178ms","start":"2026-10-09T10:07:01.794856Z","end":"2026-10-09T10:07:01.993588Z","steps":["trace[1924742829] 'agreement among raft nodes before linearized reading' (duration: 198.555214ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:01.993355Z","caller":"traceutil/trace.go:171","msg":"trace[906843658] linearizableReadLoop","detail":"{readStateIndex:27215; appliedIndex:27214; }","duration":"198.248148ms","start":"2026-10-09T10:07:01.794912Z","end":"2026-10-09T10:07:01.99316Z","steps":["trace[906843658] 'read index received' (duration: 168.666767ms)","trace[906843658] 'applied index is now lower than readState.Index' (duration: 29.580171ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:02.023147Z","caller":"traceutil/trace.go:171","msg":"trace[1254185298] transaction","detail":"{read_only:false; response_revision:26351; number_of_response:1; }","duration":"122.301642ms","start":"2026-10-09T10:07:01.900817Z","end":"2026-10-09T10:07:02.023119Z","steps":["trace[1254185298] 'process raft request' (duration: 122.091387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:02.023151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.595439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:02.023327Z","caller":"traceutil/trace.go:171","msg":"trace[694125140] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:26351; }","duration":"135.798833ms","start":"2026-10-09T10:07:01.887517Z","end":"2026-10-09T10:07:02.023315Z","steps":["trace[694125140] 'agreement among raft nodes before linearized reading' (duration: 135.542718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:02.251721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.7928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:07:02.251824Z","caller":"traceutil/trace.go:171","msg":"trace[1838296959] transaction","detail":"{read_only:false; response_revision:26352; number_of_response:1; }","duration":"253.304797ms","start":"2026-10-09T10:07:01.998501Z","end":"2026-10-09T10:07:02.251806Z","steps":["trace[1838296959] 'process raft request' (duration: 135.308062ms)","trace[1838296959] 'compare' (duration: 117.678478ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:02.631052Z","caller":"traceutil/trace.go:171","msg":"trace[54508461] linearizableReadLoop","detail":"{readStateIndex:27218; appliedIndex:27217; }","duration":"190.247699ms","start":"2026-10-09T10:07:02.440781Z","end":"2026-10-09T10:07:02.631028Z","steps":["trace[54508461] 'read index received' (duration: 190.037734ms)","trace[54508461] 'applied index is now lower than readState.Index' (duration: 209.125µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:02.631386Z","caller":"traceutil/trace.go:171","msg":"trace[1712156279] transaction","detail":"{read_only:false; response_revision:26353; number_of_response:1; }","duration":"212.848314ms","start":"2026-10-09T10:07:02.418524Z","end":"2026-10-09T10:07:02.631372Z","steps":["trace[1712156279] 'process raft request' (duration: 212.348513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:02.631709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.895443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:02.631802Z","caller":"traceutil/trace.go:171","msg":"trace[799475356] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26353; }","duration":"191.042287ms","start":"2026-10-09T10:07:02.440736Z","end":"2026-10-09T10:07:02.631778Z","steps":["trace[799475356] 'agreement among raft nodes before linearized reading' (duration: 190.855402ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:04.902829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.017238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-09T10:07:04.902921Z","caller":"traceutil/trace.go:171","msg":"trace[1054487091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26370; }","duration":"115.162162ms","start":"2026-10-09T10:07:04.787735Z","end":"2026-10-09T10:07:04.902897Z","steps":["trace[1054487091] 'range keys from in-memory index tree' (duration: 114.833315ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:05.137917Z","caller":"traceutil/trace.go:171","msg":"trace[782675833] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"205.529531ms","start":"2026-10-09T10:07:04.932365Z","end":"2026-10-09T10:07:05.137894Z","steps":["trace[782675833] 'process raft request' (duration: 203.472504ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:10.785488Z","caller":"traceutil/trace.go:171","msg":"trace[2128543723] transaction","detail":"{read_only:false; response_revision:26435; number_of_response:1; }","duration":"119.905928ms","start":"2026-10-09T10:07:10.665554Z","end":"2026-10-09T10:07:10.785459Z","steps":["trace[2128543723] 'process raft request' (duration: 119.742245ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:11.311741Z","caller":"traceutil/trace.go:171","msg":"trace[807570683] transaction","detail":"{read_only:false; response_revision:26436; number_of_response:1; }","duration":"221.509858ms","start":"2026-10-09T10:07:11.090196Z","end":"2026-10-09T10:07:11.311706Z","steps":["trace[807570683] 'process raft request' (duration: 221.266132ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:11.40135Z","caller":"traceutil/trace.go:171","msg":"trace[1398899947] transaction","detail":"{read_only:false; response_revision:26437; number_of_response:1; }","duration":"156.680879ms","start":"2026-10-09T10:07:11.244639Z","end":"2026-10-09T10:07:11.40132Z","steps":["trace[1398899947] 'process raft request' (duration: 143.914394ms)","trace[1398899947] 'compare' (duration: 12.58073ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:11.401696Z","caller":"traceutil/trace.go:171","msg":"trace[645878058] linearizableReadLoop","detail":"{readStateIndex:27306; appliedIndex:27303; }","duration":"147.960654ms","start":"2026-10-09T10:07:11.25372Z","end":"2026-10-09T10:07:11.401681Z","steps":["trace[645878058] 'read index received' (duration: 57.746139ms)","trace[645878058] 'applied index is now lower than readState.Index' (duration: 90.213755ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:11.401758Z","caller":"traceutil/trace.go:171","msg":"trace[145889377] transaction","detail":"{read_only:false; response_revision:26438; number_of_response:1; }","duration":"156.750401ms","start":"2026-10-09T10:07:11.244951Z","end":"2026-10-09T10:07:11.401701Z","steps":["trace[145889377] 'process raft request' (duration: 156.323321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:11.402086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.346983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T10:07:11.402184Z","caller":"traceutil/trace.go:171","msg":"trace[751080944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26439; }","duration":"148.529777ms","start":"2026-10-09T10:07:11.253632Z","end":"2026-10-09T10:07:11.402162Z","steps":["trace[751080944] 'agreement among raft nodes before linearized reading' (duration: 148.153009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:14.564857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.12299ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-09T10:07:14.564996Z","caller":"traceutil/trace.go:171","msg":"trace[576080339] linearizableReadLoop","detail":"{readStateIndex:27339; appliedIndex:27338; }","duration":"204.880896ms","start":"2026-10-09T10:07:14.36009Z","end":"2026-10-09T10:07:14.564971Z","steps":["trace[576080339] 'read index received' (duration: 34.411µs)","trace[576080339] 'applied index is now lower than readState.Index' (duration: 204.844395ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:14.565358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.247324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:14.56542Z","caller":"traceutil/trace.go:171","msg":"trace[309389364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26470; }","duration":"205.326925ms","start":"2026-10-09T10:07:14.360077Z","end":"2026-10-09T10:07:14.565404Z","steps":["trace[309389364] 'agreement among raft nodes before linearized reading' (duration: 205.201383ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:14.565596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.679345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-09T10:07:14.565995Z","caller":"traceutil/trace.go:171","msg":"trace[1946825030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26470; }","duration":"163.860879ms","start":"2026-10-09T10:07:14.401839Z","end":"2026-10-09T10:07:14.5657Z","steps":["trace[1946825030] 'agreement among raft nodes before linearized reading' (duration: 163.537182ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:14.709711Z","caller":"traceutil/trace.go:171","msg":"trace[560362729] transaction","detail":"{read_only:false; response_revision:26471; number_of_response:1; }","duration":"139.042255ms","start":"2026-10-09T10:07:14.570639Z","end":"2026-10-09T10:07:14.709682Z","steps":["trace[560362729] 'process raft request' (duration: 138.817569ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:16.932934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.404965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:16.933004Z","caller":"traceutil/trace.go:171","msg":"trace[1520649953] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26488; }","duration":"127.530978ms","start":"2026-10-09T10:07:16.805456Z","end":"2026-10-09T10:07:16.932987Z","steps":["trace[1520649953] 'count revisions from in-memory index tree' (duration: 127.303073ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:16.933165Z","caller":"traceutil/trace.go:171","msg":"trace[906577661] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"127.250202ms","start":"2026-10-09T10:07:16.805893Z","end":"2026-10-09T10:07:16.933143Z","steps":["trace[906577661] 'process raft request' (duration: 84.859765ms)","trace[906577661] 'compare' (duration: 42.299115ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:21.550391Z","caller":"traceutil/trace.go:171","msg":"trace[1039391163] linearizableReadLoop","detail":"{readStateIndex:27389; appliedIndex:27388; }","duration":"113.76641ms","start":"2026-10-09T10:07:21.436584Z","end":"2026-10-09T10:07:21.55035Z","steps":["trace[1039391163] 'read index received' (duration: 46.60757ms)","trace[1039391163] 'applied index is now lower than readState.Index' (duration: 67.15719ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:21.550503Z","caller":"traceutil/trace.go:171","msg":"trace[280922077] transaction","detail":"{read_only:false; response_revision:26519; number_of_response:1; }","duration":"135.527186ms","start":"2026-10-09T10:07:21.414923Z","end":"2026-10-09T10:07:21.550451Z","steps":["trace[280922077] 'process raft request' (duration: 68.238373ms)","trace[280922077] 'compare' (duration: 67.006727ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:21.550677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.035067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:21.550723Z","caller":"traceutil/trace.go:171","msg":"trace[1521577836] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:26519; }","duration":"114.228461ms","start":"2026-10-09T10:07:21.436482Z","end":"2026-10-09T10:07:21.55071Z","steps":["trace[1521577836] 'agreement among raft nodes before linearized reading' (duration: 114.045976ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:21.664628Z","caller":"traceutil/trace.go:171","msg":"trace[429692128] transaction","detail":"{read_only:false; response_revision:26520; number_of_response:1; }","duration":"222.023609ms","start":"2026-10-09T10:07:21.442559Z","end":"2026-10-09T10:07:21.664583Z","steps":["trace[429692128] 'process raft request' (duration: 217.627111ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:21.664681Z","caller":"traceutil/trace.go:171","msg":"trace[1347878212] transaction","detail":"{read_only:false; response_revision:26521; number_of_response:1; }","duration":"221.537347ms","start":"2026-10-09T10:07:21.443081Z","end":"2026-10-09T10:07:21.664618Z","steps":["trace[1347878212] 'process raft request' (duration: 221.407705ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:21.665081Z","caller":"traceutil/trace.go:171","msg":"trace[1983051160] transaction","detail":"{read_only:false; response_revision:26522; number_of_response:1; }","duration":"138.170836ms","start":"2026-10-09T10:07:21.526877Z","end":"2026-10-09T10:07:21.665048Z","steps":["trace[1983051160] 'process raft request' (duration: 137.684655ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:21.665154Z","caller":"traceutil/trace.go:171","msg":"trace[1190228211] linearizableReadLoop","detail":"{readStateIndex:27395; appliedIndex:27389; }","duration":"114.648831ms","start":"2026-10-09T10:07:21.550491Z","end":"2026-10-09T10:07:21.66514Z","steps":["trace[1190228211] 'read index received' (duration: 109.735612ms)","trace[1190228211] 'applied index is now lower than readState.Index' (duration: 4.911939ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:21.665126Z","caller":"traceutil/trace.go:171","msg":"trace[641087137] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"120.748157ms","start":"2026-10-09T10:07:21.544347Z","end":"2026-10-09T10:07:21.665095Z","steps":["trace[641087137] 'process raft request' (duration: 120.658305ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:21.665166Z","caller":"traceutil/trace.go:171","msg":"trace[324421284] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"116.843799ms","start":"2026-10-09T10:07:21.5483Z","end":"2026-10-09T10:07:21.665144Z","steps":["trace[324421284] 'process raft request' (duration: 116.747507ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:21.665123Z","caller":"traceutil/trace.go:171","msg":"trace[1915974790] transaction","detail":"{read_only:false; response_revision:26523; number_of_response:1; }","duration":"124.102491ms","start":"2026-10-09T10:07:21.540975Z","end":"2026-10-09T10:07:21.665077Z","steps":["trace[1915974790] 'process raft request' (duration: 123.876807ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:21.665573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.088101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T10:07:21.665697Z","caller":"traceutil/trace.go:171","msg":"trace[1818998530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26525; }","duration":"205.261865ms","start":"2026-10-09T10:07:21.460408Z","end":"2026-10-09T10:07:21.66567Z","steps":["trace[1818998530] 'agreement among raft nodes before linearized reading' (duration: 204.819085ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:25.047568Z","caller":"traceutil/trace.go:171","msg":"trace[2026843556] linearizableReadLoop","detail":"{readStateIndex:27416; appliedIndex:27415; }","duration":"136.619021ms","start":"2026-10-09T10:07:24.910925Z","end":"2026-10-09T10:07:25.047544Z","steps":["trace[2026843556] 'read index received' (duration: 136.458598ms)","trace[2026843556] 'applied index is now lower than readState.Index' (duration: 159.293µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:25.047698Z","caller":"traceutil/trace.go:171","msg":"trace[1070527226] transaction","detail":"{read_only:false; response_revision:26545; number_of_response:1; }","duration":"152.510537ms","start":"2026-10-09T10:07:24.895163Z","end":"2026-10-09T10:07:25.047673Z","steps":["trace[1070527226] 'process raft request' (duration: 152.18451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:25.047789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.836656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:07:25.047825Z","caller":"traceutil/trace.go:171","msg":"trace[2121371767] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26545; }","duration":"136.921328ms","start":"2026-10-09T10:07:24.910891Z","end":"2026-10-09T10:07:25.047813Z","steps":["trace[2121371767] 'agreement among raft nodes before linearized reading' (duration: 136.787185ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:25.223085Z","caller":"traceutil/trace.go:171","msg":"trace[581651357] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"257.724096ms","start":"2026-10-09T10:07:24.965331Z","end":"2026-10-09T10:07:25.223056Z","steps":["trace[581651357] 'process raft request' (duration: 196.212014ms)","trace[581651357] 'compare' (duration: 61.3858ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:27.296989Z","caller":"traceutil/trace.go:171","msg":"trace[2019047970] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"109.614518ms","start":"2026-10-09T10:07:27.187307Z","end":"2026-10-09T10:07:27.296922Z","steps":["trace[2019047970] 'process raft request' (duration: 33.495328ms)","trace[2019047970] 'compare' (duration: 75.225949ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:32.284639Z","caller":"traceutil/trace.go:171","msg":"trace[47132391] transaction","detail":"{read_only:false; response_revision:26614; number_of_response:1; }","duration":"156.100006ms","start":"2026-10-09T10:07:32.128506Z","end":"2026-10-09T10:07:32.284606Z","steps":["trace[47132391] 'process raft request' (duration: 155.34974ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:32.3596Z","caller":"traceutil/trace.go:171","msg":"trace[2035738418] transaction","detail":"{read_only:false; response_revision:26615; number_of_response:1; }","duration":"223.741987ms","start":"2026-10-09T10:07:32.135827Z","end":"2026-10-09T10:07:32.359569Z","steps":["trace[2035738418] 'process raft request' (duration: 223.592984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:36.872599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.209013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-09T10:07:36.872697Z","caller":"traceutil/trace.go:171","msg":"trace[796480543] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26645; }","duration":"205.367186ms","start":"2026-10-09T10:07:36.667311Z","end":"2026-10-09T10:07:36.872679Z","steps":["trace[796480543] 'count revisions from in-memory index tree' (duration: 205.108971ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:48.071469Z","caller":"traceutil/trace.go:171","msg":"trace[1230854977] transaction","detail":"{read_only:false; response_revision:26732; number_of_response:1; }","duration":"102.009649ms","start":"2026-10-09T10:07:47.969432Z","end":"2026-10-09T10:07:48.071442Z","steps":["trace[1230854977] 'process raft request' (duration: 71.899296ms)","trace[1230854977] 'compare' (duration: 29.485118ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:53.369909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.325431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:53.369974Z","caller":"traceutil/trace.go:171","msg":"trace[606580115] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:26764; }","duration":"106.443653ms","start":"2026-10-09T10:07:53.263514Z","end":"2026-10-09T10:07:53.369958Z","steps":["trace[606580115] 'count revisions from in-memory index tree' (duration: 106.177668ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:53.498543Z","caller":"traceutil/trace.go:171","msg":"trace[682381641] linearizableReadLoop","detail":"{readStateIndex:27642; appliedIndex:27641; }","duration":"110.792741ms","start":"2026-10-09T10:07:53.387723Z","end":"2026-10-09T10:07:53.498516Z","steps":["trace[682381641] 'read index received' (duration: 110.643558ms)","trace[682381641] 'applied index is now lower than readState.Index' (duration: 147.753µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:53.498607Z","caller":"traceutil/trace.go:171","msg":"trace[842170288] transaction","detail":"{read_only:false; response_revision:26765; number_of_response:1; }","duration":"123.764529ms","start":"2026-10-09T10:07:53.374783Z","end":"2026-10-09T10:07:53.498547Z","steps":["trace[842170288] 'process raft request' (duration: 123.546085ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:53.498745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.991165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:53.498829Z","caller":"traceutil/trace.go:171","msg":"trace[202389433] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:26765; }","duration":"111.115708ms","start":"2026-10-09T10:07:53.387685Z","end":"2026-10-09T10:07:53.4988Z","steps":["trace[202389433] 'agreement among raft nodes before linearized reading' (duration: 110.917744ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:54.059591Z","caller":"traceutil/trace.go:171","msg":"trace[661811056] transaction","detail":"{read_only:false; response_revision:26770; number_of_response:1; }","duration":"178.161092ms","start":"2026-10-09T10:07:53.881404Z","end":"2026-10-09T10:07:54.059565Z","steps":["trace[661811056] 'process raft request' (duration: 178.08583ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:54.059885Z","caller":"traceutil/trace.go:171","msg":"trace[1175470269] transaction","detail":"{read_only:false; response_revision:26771; number_of_response:1; }","duration":"176.425474ms","start":"2026-10-09T10:07:53.883434Z","end":"2026-10-09T10:07:54.059859Z","steps":["trace[1175470269] 'process raft request' (duration: 176.099267ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:54.059922Z","caller":"traceutil/trace.go:171","msg":"trace[206866056] transaction","detail":"{read_only:false; response_revision:26769; number_of_response:1; }","duration":"192.704536ms","start":"2026-10-09T10:07:53.867191Z","end":"2026-10-09T10:07:54.059895Z","steps":["trace[206866056] 'process raft request' (duration: 158.360091ms)","trace[206866056] 'compare' (duration: 33.765242ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:54.06055Z","caller":"traceutil/trace.go:171","msg":"trace[2136824029] transaction","detail":"{read_only:false; response_revision:26772; number_of_response:1; }","duration":"169.852967ms","start":"2026-10-09T10:07:53.890681Z","end":"2026-10-09T10:07:54.060534Z","steps":["trace[2136824029] 'process raft request' (duration: 169.098099ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:54.060593Z","caller":"traceutil/trace.go:171","msg":"trace[1000374012] transaction","detail":"{read_only:false; response_revision:26773; number_of_response:1; }","duration":"165.812598ms","start":"2026-10-09T10:07:53.894752Z","end":"2026-10-09T10:07:54.060565Z","steps":["trace[1000374012] 'process raft request' (duration: 165.657613ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:54.060683Z","caller":"traceutil/trace.go:171","msg":"trace[1576131572] linearizableReadLoop","detail":"{readStateIndex:27651; appliedIndex:27645; }","duration":"163.68512ms","start":"2026-10-09T10:07:53.896981Z","end":"2026-10-09T10:07:54.060667Z","steps":["trace[1576131572] 'read index received' (duration: 128.587277ms)","trace[1576131572] 'applied index is now lower than readState.Index' (duration: 35.096543ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:07:54.060714Z","caller":"traceutil/trace.go:171","msg":"trace[1535754535] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"165.777707ms","start":"2026-10-09T10:07:53.894926Z","end":"2026-10-09T10:07:54.060704Z","steps":["trace[1535754535] 'process raft request' (duration: 165.602603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:54.060886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.594464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-10-09T10:07:54.060884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.885704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-09T10:07:54.060918Z","caller":"traceutil/trace.go:171","msg":"trace[934166934] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26774; }","duration":"144.652795ms","start":"2026-10-09T10:07:53.916254Z","end":"2026-10-09T10:07:54.060907Z","steps":["trace[934166934] 'agreement among raft nodes before linearized reading' (duration: 144.543493ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:54.060924Z","caller":"traceutil/trace.go:171","msg":"trace[413073158] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26774; }","duration":"163.968316ms","start":"2026-10-09T10:07:53.896944Z","end":"2026-10-09T10:07:54.060912Z","steps":["trace[413073158] 'agreement among raft nodes before linearized reading' (duration: 163.819353ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:54.061138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.086543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-09T10:07:54.061182Z","caller":"traceutil/trace.go:171","msg":"trace[31538952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26774; }","duration":"140.180905ms","start":"2026-10-09T10:07:53.920992Z","end":"2026-10-09T10:07:54.061173Z","steps":["trace[31538952] 'agreement among raft nodes before linearized reading' (duration: 140.038782ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:07:58.555944Z","caller":"traceutil/trace.go:171","msg":"trace[477849701] transaction","detail":"{read_only:false; response_revision:26808; number_of_response:1; }","duration":"102.860793ms","start":"2026-10-09T10:07:58.453055Z","end":"2026-10-09T10:07:58.555915Z","steps":["trace[477849701] 'process raft request' (duration: 102.663419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:07:59.221054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.09717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:07:59.22124Z","caller":"traceutil/trace.go:171","msg":"trace[406291819] transaction","detail":"{read_only:false; response_revision:26809; number_of_response:1; }","duration":"262.101955ms","start":"2026-10-09T10:07:58.959115Z","end":"2026-10-09T10:07:59.221217Z","steps":["trace[406291819] 'process raft request' (duration: 127.753359ms)","trace[406291819] 'compare' (duration: 133.66642ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:07:59.426963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.358439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:07:59.427128Z","caller":"traceutil/trace.go:171","msg":"trace[463033234] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26809; }","duration":"105.578074ms","start":"2026-10-09T10:07:59.32153Z","end":"2026-10-09T10:07:59.427108Z","steps":["trace[463033234] 'range keys from in-memory index tree' (duration: 105.231825ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:00.212427Z","caller":"traceutil/trace.go:171","msg":"trace[585168722] transaction","detail":"{read_only:false; response_revision:26813; number_of_response:1; }","duration":"124.532058ms","start":"2026-10-09T10:08:00.087879Z","end":"2026-10-09T10:08:00.212411Z","steps":["trace[585168722] 'process raft request' (duration: 124.428806ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:00.414508Z","caller":"traceutil/trace.go:171","msg":"trace[184766569] transaction","detail":"{read_only:false; response_revision:26814; number_of_response:1; }","duration":"318.244016ms","start":"2026-10-09T10:08:00.096239Z","end":"2026-10-09T10:08:00.414483Z","steps":["trace[184766569] 'process raft request' (duration: 318.020601ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:00.414687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.096222Z","time spent":"318.373109ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T10:08:00.414898Z","caller":"traceutil/trace.go:171","msg":"trace[335064877] transaction","detail":"{read_only:false; response_revision:26815; number_of_response:1; }","duration":"318.469281ms","start":"2026-10-09T10:08:00.09638Z","end":"2026-10-09T10:08:00.414849Z","steps":["trace[335064877] 'process raft request' (duration: 318.052592ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:00.414913Z","caller":"traceutil/trace.go:171","msg":"trace[1173263365] linearizableReadLoop","detail":"{readStateIndex:27697; appliedIndex:27691; }","duration":"257.624254ms","start":"2026-10-09T10:08:00.157268Z","end":"2026-10-09T10:08:00.414892Z","steps":["trace[1173263365] 'read index received' (duration: 55.096259ms)","trace[1173263365] 'applied index is now lower than readState.Index' (duration: 202.527005ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:00.414924Z","caller":"traceutil/trace.go:171","msg":"trace[489346228] transaction","detail":"{read_only:false; response_revision:26816; number_of_response:1; }","duration":"318.485062ms","start":"2026-10-09T10:08:00.096406Z","end":"2026-10-09T10:08:00.414891Z","steps":["trace[489346228] 'process raft request' (duration: 318.290818ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:00.414958Z","caller":"traceutil/trace.go:171","msg":"trace[1235924819] transaction","detail":"{read_only:false; response_revision:26817; number_of_response:1; }","duration":"318.527063ms","start":"2026-10-09T10:08:00.096407Z","end":"2026-10-09T10:08:00.414934Z","steps":["trace[1235924819] 'process raft request' (duration: 318.40758ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:00.415039Z","caller":"traceutil/trace.go:171","msg":"trace[1722368279] transaction","detail":"{read_only:false; response_revision:26818; number_of_response:1; }","duration":"318.547013ms","start":"2026-10-09T10:08:00.096478Z","end":"2026-10-09T10:08:00.415025Z","steps":["trace[1722368279] 'process raft request' (duration: 318.375299ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:00.415055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.096349Z","time spent":"318.615405ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:08:00.415082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.096347Z","time spent":"318.671206ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:08:00.415146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.096467Z","time spent":"318.607094ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:08:00.415089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T10:08:00.09638Z","time spent":"318.617074ms","remote":"127.0.0.1:35780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T10:08:00.416894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.754127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-09T10:08:00.416973Z","caller":"traceutil/trace.go:171","msg":"trace[878821025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26818; }","duration":"248.858399ms","start":"2026-10-09T10:08:00.168081Z","end":"2026-10-09T10:08:00.41694Z","steps":["trace[878821025] 'agreement among raft nodes before linearized reading' (duration: 247.063999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:00.417472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.192171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-10-09T10:08:00.417517Z","caller":"traceutil/trace.go:171","msg":"trace[558809999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26818; }","duration":"260.275613ms","start":"2026-10-09T10:08:00.15723Z","end":"2026-10-09T10:08:00.417506Z","steps":["trace[558809999] 'agreement among raft nodes before linearized reading' (duration: 257.953361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:00.417768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.129791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T10:08:00.417833Z","caller":"traceutil/trace.go:171","msg":"trace[449070538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26818; }","duration":"243.257494ms","start":"2026-10-09T10:08:00.174554Z","end":"2026-10-09T10:08:00.417811Z","steps":["trace[449070538] 'agreement among raft nodes before linearized reading' (duration: 240.671516ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:00.679737Z","caller":"traceutil/trace.go:171","msg":"trace[1087038568] transaction","detail":"{read_only:false; response_revision:26819; number_of_response:1; }","duration":"254.593357ms","start":"2026-10-09T10:08:00.42511Z","end":"2026-10-09T10:08:00.679704Z","steps":["trace[1087038568] 'process raft request' (duration: 254.311811ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:00.681164Z","caller":"traceutil/trace.go:171","msg":"trace[1923773243] transaction","detail":"{read_only:false; response_revision:26820; number_of_response:1; }","duration":"255.855324ms","start":"2026-10-09T10:08:00.425274Z","end":"2026-10-09T10:08:00.681129Z","steps":["trace[1923773243] 'process raft request' (duration: 255.636899ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:00.681385Z","caller":"traceutil/trace.go:171","msg":"trace[909365287] linearizableReadLoop","detail":"{readStateIndex:27700; appliedIndex:27697; }","duration":"178.06131ms","start":"2026-10-09T10:08:00.503299Z","end":"2026-10-09T10:08:00.68136Z","steps":["trace[909365287] 'read index received' (duration: 176.096056ms)","trace[909365287] 'applied index is now lower than readState.Index' (duration: 1.963904ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:00.681483Z","caller":"traceutil/trace.go:171","msg":"trace[854553861] transaction","detail":"{read_only:false; response_revision:26822; number_of_response:1; }","duration":"116.531209ms","start":"2026-10-09T10:08:00.564913Z","end":"2026-10-09T10:08:00.681444Z","steps":["trace[854553861] 'process raft request' (duration: 116.351525ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:00.681599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.286216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-09T10:08:00.681503Z","caller":"traceutil/trace.go:171","msg":"trace[1976657764] transaction","detail":"{read_only:false; response_revision:26821; number_of_response:1; }","duration":"256.10449ms","start":"2026-10-09T10:08:00.425371Z","end":"2026-10-09T10:08:00.681476Z","steps":["trace[1976657764] 'process raft request' (duration: 255.695271ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:00.681686Z","caller":"traceutil/trace.go:171","msg":"trace[120150896] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26822; }","duration":"178.383928ms","start":"2026-10-09T10:08:00.50328Z","end":"2026-10-09T10:08:00.681664Z","steps":["trace[120150896] 'agreement among raft nodes before linearized reading' (duration: 178.164983ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:20.607347Z","caller":"traceutil/trace.go:171","msg":"trace[1051248387] transaction","detail":"{read_only:false; response_revision:27113; number_of_response:1; }","duration":"117.49646ms","start":"2026-10-09T10:08:20.489796Z","end":"2026-10-09T10:08:20.607293Z","steps":["trace[1051248387] 'process raft request' (duration: 117.317846ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:20.757266Z","caller":"traceutil/trace.go:171","msg":"trace[800645885] transaction","detail":"{read_only:false; response_revision:27115; number_of_response:1; }","duration":"258.953053ms","start":"2026-10-09T10:08:20.498287Z","end":"2026-10-09T10:08:20.75724Z","steps":["trace[800645885] 'process raft request' (duration: 258.877932ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:20.757446Z","caller":"traceutil/trace.go:171","msg":"trace[1378650012] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"261.667204ms","start":"2026-10-09T10:08:20.495768Z","end":"2026-10-09T10:08:20.757435Z","steps":["trace[1378650012] 'process raft request' (duration: 261.229774ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:20.757669Z","caller":"traceutil/trace.go:171","msg":"trace[1869432089] transaction","detail":"{read_only:false; response_revision:27116; number_of_response:1; }","duration":"257.926521ms","start":"2026-10-09T10:08:20.499683Z","end":"2026-10-09T10:08:20.757609Z","steps":["trace[1869432089] 'process raft request' (duration: 257.522062ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:20.757756Z","caller":"traceutil/trace.go:171","msg":"trace[1572709723] linearizableReadLoop","detail":"{readStateIndex:28001; appliedIndex:27997; }","duration":"248.669474ms","start":"2026-10-09T10:08:20.509074Z","end":"2026-10-09T10:08:20.757743Z","steps":["trace[1572709723] 'read index received' (duration: 98.326682ms)","trace[1572709723] 'applied index is now lower than readState.Index' (duration: 150.341942ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:08:20.757908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.816738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:20.757877Z","caller":"traceutil/trace.go:171","msg":"trace[1873481202] transaction","detail":"{read_only:false; response_revision:27117; number_of_response:1; }","duration":"253.497932ms","start":"2026-10-09T10:08:20.504333Z","end":"2026-10-09T10:08:20.757831Z","steps":["trace[1873481202] 'process raft request' (duration: 253.210416ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:20.757952Z","caller":"traceutil/trace.go:171","msg":"trace[369972260] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:27117; }","duration":"248.917121ms","start":"2026-10-09T10:08:20.509023Z","end":"2026-10-09T10:08:20.75794Z","steps":["trace[369972260] 'agreement among raft nodes before linearized reading' (duration: 248.767027ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:21.072754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.391732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-09T10:08:21.072863Z","caller":"traceutil/trace.go:171","msg":"trace[460793175] linearizableReadLoop","detail":"{readStateIndex:28003; appliedIndex:28002; }","duration":"276.68088ms","start":"2026-10-09T10:08:20.796163Z","end":"2026-10-09T10:08:21.072844Z","steps":["trace[460793175] 'read index received' (duration: 140.072173ms)","trace[460793175] 'applied index is now lower than readState.Index' (duration: 136.607507ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:08:21.073095Z","caller":"traceutil/trace.go:171","msg":"trace[182889614] transaction","detail":"{read_only:false; response_revision:27119; number_of_response:1; }","duration":"282.246644ms","start":"2026-10-09T10:08:20.79083Z","end":"2026-10-09T10:08:21.073076Z","steps":["trace[182889614] 'process raft request' (duration: 145.414742ms)","trace[182889614] 'compare' (duration: 136.221728ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T10:08:21.073342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.965616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:21.073425Z","caller":"traceutil/trace.go:171","msg":"trace[1268911260] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:27119; }","duration":"120.109509ms","start":"2026-10-09T10:08:20.953294Z","end":"2026-10-09T10:08:21.073403Z","steps":["trace[1268911260] 'agreement among raft nodes before linearized reading' (duration: 119.953365ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:21.07348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.562672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-09T10:08:21.073544Z","caller":"traceutil/trace.go:171","msg":"trace[784955293] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:27119; }","duration":"126.683965ms","start":"2026-10-09T10:08:20.946845Z","end":"2026-10-09T10:08:21.073529Z","steps":["trace[784955293] 'agreement among raft nodes before linearized reading' (duration: 126.442469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:21.073393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.795932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-09T10:08:21.073733Z","caller":"traceutil/trace.go:171","msg":"trace[160856374] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:27119; }","duration":"107.181221ms","start":"2026-10-09T10:08:20.966529Z","end":"2026-10-09T10:08:21.07371Z","steps":["trace[160856374] 'agreement among raft nodes before linearized reading' (duration: 106.735911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T10:08:21.073546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.340605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-09T10:08:21.073578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.005049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-09T10:08:21.07425Z","caller":"traceutil/trace.go:171","msg":"trace[829628609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27119; }","duration":"265.751897ms","start":"2026-10-09T10:08:20.80846Z","end":"2026-10-09T10:08:21.074212Z","steps":["trace[829628609] 'agreement among raft nodes before linearized reading' (duration: 264.778764ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:21.07409Z","caller":"traceutil/trace.go:171","msg":"trace[1042330608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27119; }","duration":"277.962689ms","start":"2026-10-09T10:08:20.796107Z","end":"2026-10-09T10:08:21.07407Z","steps":["trace[1042330608] 'agreement among raft nodes before linearized reading' (duration: 277.09742ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T10:08:21.233751Z","caller":"traceutil/trace.go:171","msg":"trace[1776501898] transaction","detail":"{read_only:false; response_revision:27121; number_of_response:1; }","duration":"154.76495ms","start":"2026-10-09T10:08:21.078951Z","end":"2026-10-09T10:08:21.233716Z","steps":["trace[1776501898] 'process raft request' (duration: 130.97413ms)","trace[1776501898] 'compare' (duration: 23.623416ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T10:09:25.29146Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24822} {"level":"info","ts":"2026-10-09T10:09:25.445757Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24822,"took":"150.422674ms","hash":575738518,"current-db-size-bytes":57135104,"current-db-size":"57 MB","current-db-size-in-use-bytes":36708352,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-09T10:09:25.445819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":575738518,"revision":24822,"compact-revision":21504} {"level":"info","ts":"2026-10-09T10:11:39.676036Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"afeb1bf8b692f9e8","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T10:11:39.682323Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-10-09T10:11:39.682454Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-10-09T10:14:25.299028Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27684} {"level":"info","ts":"2026-10-09T10:14:25.446413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27684,"took":"143.646578ms","hash":3198892909,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":34476032,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-09T10:14:25.446518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3198892909,"revision":27684,"compact-revision":24822} {"level":"info","ts":"2026-10-09T10:19:25.306893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30327} {"level":"info","ts":"2026-10-09T10:19:25.453821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30327,"took":"142.406131ms","hash":2935293382,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":30720000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-09T10:19:25.453896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2935293382,"revision":30327,"compact-revision":27684} {"level":"info","ts":"2026-10-09T10:24:25.314312Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32576} {"level":"info","ts":"2026-10-09T10:24:25.485784Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32576,"took":"166.686473ms","hash":2538828507,"current-db-size-bytes":59449344,"current-db-size":"59 MB","current-db-size-in-use-bytes":30351360,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T10:24:25.485859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2538828507,"revision":32576,"compact-revision":30327} {"level":"info","ts":"2026-10-09T10:29:25.322238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34810} {"level":"info","ts":"2026-10-09T10:29:25.464081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34810,"took":"137.767767ms","hash":54750406,"current-db-size-bytes":59449344,"current-db-size":"59 MB","current-db-size-in-use-bytes":31748096,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T10:29:25.464296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":54750406,"revision":34810,"compact-revision":32576} {"level":"info","ts":"2026-10-09T10:33:04.64316Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"afeb1bf8b692f9e8","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-09T10:33:04.65031Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-10-09T10:33:04.650543Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-10-09T10:34:25.330322Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37105} {"level":"info","ts":"2026-10-09T10:34:25.460564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37105,"took":"125.994046ms","hash":3178559664,"current-db-size-bytes":59449344,"current-db-size":"59 MB","current-db-size-in-use-bytes":32571392,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-09T10:34:25.460648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3178559664,"revision":37105,"compact-revision":34810} {"level":"info","ts":"2026-10-09T10:39:25.340579Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39387} {"level":"info","ts":"2026-10-09T10:39:25.478288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39387,"took":"132.801058ms","hash":487058328,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":31633408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-09T10:39:25.478363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":487058328,"revision":39387,"compact-revision":37105} {"level":"info","ts":"2026-10-09T10:44:25.347825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41619} {"level":"info","ts":"2026-10-09T10:44:25.480183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41619,"took":"126.641385ms","hash":3620822062,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":29802496,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-09T10:44:25.480272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3620822062,"revision":41619,"compact-revision":39387} {"level":"info","ts":"2026-10-09T10:49:25.355806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":43847} {"level":"info","ts":"2026-10-09T10:49:25.487657Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":43847,"took":"128.126489ms","hash":2852867122,"current-db-size-bytes":59875328,"current-db-size":"60 MB","current-db-size-in-use-bytes":29290496,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T10:49:25.487734Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2852867122,"revision":43847,"compact-revision":41619}