{"level":"warn","ts":"2026-08-05T07:13:09.449582Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T07:13:09.449673Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-08-05T07:13:09.44975Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T07:13:09.449758Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-08-05T07:13:09.44978Z","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-08-05T07:13:09.450284Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-08-05T07:13:09.450363Z","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://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-08-05T07:13:09.453803Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.235927ms"} {"level":"info","ts":"2026-08-05T07:13:09.45965Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-08-05T07:13:09.459755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T07:13:09.459791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-08-05T07:13:09.459815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T07:13:09.459833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-08-05T07:13:09.459883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-08-05T07:13:09.47237Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T07:13:09.475235Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T07:13:09.476615Z","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-08-05T07:13:09.479033Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T07:13:09.479266Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T07:13:09.479297Z","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-08-05T07:13:09.479404Z","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-08-05T07:13:09.479502Z","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-08-05T07:13:09.483452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-08-05T07:13:09.483618Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-08-05T07:13:09.484794Z","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-08-05T07:13:09.484902Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-08-05T07:13:09.484969Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-08-05T07:13:09.485257Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T07:13:09.485351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T07:13:10.260706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T07:13:10.260753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T07:13:10.260776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-08-05T07:13:10.260787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-08-05T07:13:10.260791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-08-05T07:13:10.2608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-08-05T07:13:10.260806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-08-05T07:13:10.262211Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T07:13:10.26301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T07:13:10.262999Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T07:13:10.263041Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T07:13:10.263307Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T07:13:10.263358Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T07:13:10.263687Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T07:13:10.263772Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T07:13:10.263799Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T07:13:10.266165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-08-05T07:13:10.266628Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-05T07:13:50.09782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.727617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T07:13:50.097964Z","caller":"traceutil/trace.go:171","msg":"trace[1386562533] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"153.528282ms","start":"2026-08-05T07:13:49.944425Z","end":"2026-08-05T07:13:50.097953Z","steps":["trace[1386562533] 'process raft request' (duration: 153.487591ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:13:50.097981Z","caller":"traceutil/trace.go:171","msg":"trace[565703398] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"180.843736ms","start":"2026-08-05T07:13:49.917093Z","end":"2026-08-05T07:13:50.097936Z","steps":["trace[565703398] 'process raft request' (duration: 10.658269ms)","trace[565703398] 'compare' (duration: 169.624325ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:13:50.098291Z","caller":"traceutil/trace.go:171","msg":"trace[1132868513] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"162.086709ms","start":"2026-08-05T07:13:49.936059Z","end":"2026-08-05T07:13:50.098145Z","steps":["trace[1132868513] 'read index received' (duration: 50.321µs)","trace[1132868513] 'applied index is now lower than readState.Index' (duration: 162.034638ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:13:50.098423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.366425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-08-05T07:13:50.098446Z","caller":"traceutil/trace.go:171","msg":"trace[106888502] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:583; }","duration":"162.416606ms","start":"2026-08-05T07:13:49.936021Z","end":"2026-08-05T07:13:50.098438Z","steps":["trace[106888502] 'agreement among raft nodes before linearized reading' (duration: 162.300354ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:13:56.231023Z","caller":"traceutil/trace.go:171","msg":"trace[84725981] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"100.965177ms","start":"2026-08-05T07:13:56.130029Z","end":"2026-08-05T07:13:56.230994Z","steps":["trace[84725981] 'process raft request' (duration: 100.392375ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:13:56.246676Z","caller":"traceutil/trace.go:171","msg":"trace[138898448] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"115.489656ms","start":"2026-08-05T07:13:56.131155Z","end":"2026-08-05T07:13:56.246644Z","steps":["trace[138898448] 'process raft request' (duration: 115.244111ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:13:56.447962Z","caller":"traceutil/trace.go:171","msg":"trace[272569820] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"128.624968ms","start":"2026-08-05T07:13:56.319302Z","end":"2026-08-05T07:13:56.447927Z","steps":["trace[272569820] 'read index received' (duration: 90.908748ms)","trace[272569820] 'applied index is now lower than readState.Index' (duration: 37.71467ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:13:56.448062Z","caller":"traceutil/trace.go:171","msg":"trace[1694900678] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"160.111338ms","start":"2026-08-05T07:13:56.287916Z","end":"2026-08-05T07:13:56.448028Z","steps":["trace[1694900678] 'process raft request' (duration: 122.343507ms)","trace[1694900678] 'compare' (duration: 37.508825ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:13:56.448282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.915783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rs4bn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-05T07:13:56.448369Z","caller":"traceutil/trace.go:171","msg":"trace[971546546] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rs4bn; range_end:; response_count:1; response_revision:640; }","duration":"129.061426ms","start":"2026-08-05T07:13:56.319296Z","end":"2026-08-05T07:13:56.448357Z","steps":["trace[971546546] 'agreement among raft nodes before linearized reading' (duration: 128.75934ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:13:56.677838Z","caller":"traceutil/trace.go:171","msg":"trace[614950729] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"106.95624ms","start":"2026-08-05T07:13:56.57085Z","end":"2026-08-05T07:13:56.677806Z","steps":["trace[614950729] 'read index received' (duration: 85.965455ms)","trace[614950729] 'applied index is now lower than readState.Index' (duration: 20.989175ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:13:56.677931Z","caller":"traceutil/trace.go:171","msg":"trace[244702400] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"210.846846ms","start":"2026-08-05T07:13:56.46704Z","end":"2026-08-05T07:13:56.677887Z","steps":["trace[244702400] 'process raft request' (duration: 189.75062ms)","trace[244702400] 'compare' (duration: 20.759859ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:13:56.678025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.145813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T07:13:56.678076Z","caller":"traceutil/trace.go:171","msg":"trace[640200877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"107.224635ms","start":"2026-08-05T07:13:56.57084Z","end":"2026-08-05T07:13:56.678065Z","steps":["trace[640200877] 'agreement among raft nodes before linearized reading' (duration: 107.076312ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:13:57.179526Z","caller":"traceutil/trace.go:171","msg":"trace[156896487] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"300.641032ms","start":"2026-08-05T07:13:56.878848Z","end":"2026-08-05T07:13:57.179489Z","steps":["trace[156896487] 'process raft request' (duration: 299.859616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:13:57.179745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T07:13:56.878811Z","time spent":"300.822536ms","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036233,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T07:14:12.175521Z","caller":"traceutil/trace.go:171","msg":"trace[861897535] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"166.47669ms","start":"2026-08-05T07:14:12.00902Z","end":"2026-08-05T07:14:12.175497Z","steps":["trace[861897535] 'read index received' (duration: 166.351478ms)","trace[861897535] 'applied index is now lower than readState.Index' (duration: 123.652µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:14:12.17573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.699904ms","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-08-05T07:14:12.175773Z","caller":"traceutil/trace.go:171","msg":"trace[632313625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"166.786236ms","start":"2026-08-05T07:14:12.008971Z","end":"2026-08-05T07:14:12.175758Z","steps":["trace[632313625] 'agreement among raft nodes before linearized reading' (duration: 166.636573ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:14:12.175843Z","caller":"traceutil/trace.go:171","msg":"trace[1313459568] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"183.126934ms","start":"2026-08-05T07:14:11.992685Z","end":"2026-08-05T07:14:12.175812Z","steps":["trace[1313459568] 'process raft request' (duration: 182.653624ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:14:57.860027Z","caller":"traceutil/trace.go:171","msg":"trace[833258378] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"103.653653ms","start":"2026-08-05T07:14:57.756309Z","end":"2026-08-05T07:14:57.859962Z","steps":["trace[833258378] 'process raft request' (duration: 103.327626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:15:21.683032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.1072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f510cb4-a4b9-4277-bbd6-d5b207fb2406\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-05T07:15:21.683108Z","caller":"traceutil/trace.go:171","msg":"trace[54541555] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f510cb4-a4b9-4277-bbd6-d5b207fb2406; range_end:; response_count:1; response_revision:1807; }","duration":"322.247593ms","start":"2026-08-05T07:15:21.360844Z","end":"2026-08-05T07:15:21.683091Z","steps":["trace[54541555] 'range keys from in-memory index tree' (duration: 321.938376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:15:21.683137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T07:15:21.360814Z","time spent":"322.317004ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f510cb4-a4b9-4277-bbd6-d5b207fb2406\" "} {"level":"warn","ts":"2026-08-05T07:15:21.683272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.420058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T07:15:21.683368Z","caller":"traceutil/trace.go:171","msg":"trace[1147076728] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1807; }","duration":"325.591371ms","start":"2026-08-05T07:15:21.357754Z","end":"2026-08-05T07:15:21.683346Z","steps":["trace[1147076728] 'range keys from in-memory index tree' (duration: 325.214994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:15:21.683414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T07:15:21.35773Z","time spent":"325.672044ms","remote":"127.0.0.1:46060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-05T07:15:22.188977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.250794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T07:15:22.189158Z","caller":"traceutil/trace.go:171","msg":"trace[1644289326] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"156.045257ms","start":"2026-08-05T07:15:22.03309Z","end":"2026-08-05T07:15:22.189135Z","steps":["trace[1644289326] 'process raft request' (duration: 28.503447ms)","trace[1644289326] 'compare' (duration: 127.104561ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:15:22.189166Z","caller":"traceutil/trace.go:171","msg":"trace[741132908] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"131.694044ms","start":"2026-08-05T07:15:22.057452Z","end":"2026-08-05T07:15:22.189146Z","steps":["trace[741132908] 'read index received' (duration: 4.063003ms)","trace[741132908] 'applied index is now lower than readState.Index' (duration: 127.628301ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:15:22.189364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.883249ms","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":"info","ts":"2026-08-05T07:15:22.189449Z","caller":"traceutil/trace.go:171","msg":"trace[1737448014] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1811; }","duration":"132.050872ms","start":"2026-08-05T07:15:22.057379Z","end":"2026-08-05T07:15:22.18943Z","steps":["trace[1737448014] 'agreement among raft nodes before linearized reading' (duration: 131.887059ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:16:11.026697Z","caller":"traceutil/trace.go:171","msg":"trace[963475962] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2207; }","duration":"190.15566ms","start":"2026-08-05T07:16:10.836519Z","end":"2026-08-05T07:16:11.026675Z","steps":["trace[963475962] 'read index received' (duration: 189.947076ms)","trace[963475962] 'applied index is now lower than readState.Index' (duration: 207.804µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:16:11.02677Z","caller":"traceutil/trace.go:171","msg":"trace[968168897] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"199.191026ms","start":"2026-08-05T07:16:10.82754Z","end":"2026-08-05T07:16:11.026731Z","steps":["trace[968168897] 'process raft request' (duration: 198.985712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:16:11.02697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.425496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-08-05T07:16:11.027031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.269664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-05T07:16:11.027069Z","caller":"traceutil/trace.go:171","msg":"trace[1361983166] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2125; }","duration":"190.583028ms","start":"2026-08-05T07:16:10.836464Z","end":"2026-08-05T07:16:11.027047Z","steps":["trace[1361983166] 'agreement among raft nodes before linearized reading' (duration: 190.311963ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:16:11.027091Z","caller":"traceutil/trace.go:171","msg":"trace[1570150311] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2125; }","duration":"129.348436ms","start":"2026-08-05T07:16:10.897725Z","end":"2026-08-05T07:16:11.027073Z","steps":["trace[1570150311] 'agreement among raft nodes before linearized reading' (duration: 129.112701ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:17:23.545439Z","caller":"traceutil/trace.go:171","msg":"trace[55744502] linearizableReadLoop","detail":"{readStateIndex:2671; appliedIndex:2670; }","duration":"120.615553ms","start":"2026-08-05T07:17:23.42479Z","end":"2026-08-05T07:17:23.545405Z","steps":["trace[55744502] 'read index received' (duration: 107.704428ms)","trace[55744502] 'applied index is now lower than readState.Index' (duration: 12.910005ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:17:23.545696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.870428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-05T07:17:23.545742Z","caller":"traceutil/trace.go:171","msg":"trace[1674163482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2571; }","duration":"120.966929ms","start":"2026-08-05T07:17:23.424763Z","end":"2026-08-05T07:17:23.54573Z","steps":["trace[1674163482] 'agreement among raft nodes before linearized reading' (duration: 120.778746ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:17:23.545776Z","caller":"traceutil/trace.go:171","msg":"trace[985749634] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"123.878099ms","start":"2026-08-05T07:17:23.421547Z","end":"2026-08-05T07:17:23.545425Z","steps":["trace[985749634] 'process raft request' (duration: 110.931243ms)","trace[985749634] 'compare' (duration: 12.783693ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:17:23.673508Z","caller":"traceutil/trace.go:171","msg":"trace[149186344] linearizableReadLoop","detail":"{readStateIndex:2672; appliedIndex:2671; }","duration":"103.656183ms","start":"2026-08-05T07:17:23.569822Z","end":"2026-08-05T07:17:23.673478Z","steps":["trace[149186344] 'read index received' (duration: 83.842666ms)","trace[149186344] 'applied index is now lower than readState.Index' (duration: 19.812357ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:17:23.673662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.819436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T07:17:23.673661Z","caller":"traceutil/trace.go:171","msg":"trace[33543380] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"123.101943ms","start":"2026-08-05T07:17:23.55054Z","end":"2026-08-05T07:17:23.673642Z","steps":["trace[33543380] 'process raft request' (duration: 103.212324ms)","trace[33543380] 'compare' (duration: 19.582083ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:17:23.673705Z","caller":"traceutil/trace.go:171","msg":"trace[1776821561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2572; }","duration":"103.883858ms","start":"2026-08-05T07:17:23.569808Z","end":"2026-08-05T07:17:23.673692Z","steps":["trace[1776821561] 'agreement among raft nodes before linearized reading' (duration: 103.791087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:19:54.117867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.530413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T07:19:54.118033Z","caller":"traceutil/trace.go:171","msg":"trace[1567166950] transaction","detail":"{read_only:false; response_revision:3550; number_of_response:1; }","duration":"266.645683ms","start":"2026-08-05T07:19:53.851359Z","end":"2026-08-05T07:19:54.118005Z","steps":["trace[1567166950] 'process raft request' (duration: 130.840225ms)","trace[1567166950] 'compare' (duration: 135.339309ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:19:54.137679Z","caller":"traceutil/trace.go:171","msg":"trace[2088022639] linearizableReadLoop","detail":"{readStateIndex:3690; appliedIndex:3687; }","duration":"141.900433ms","start":"2026-08-05T07:19:53.995736Z","end":"2026-08-05T07:19:54.137636Z","steps":["trace[2088022639] 'read index received' (duration: 141.508245ms)","trace[2088022639] 'applied index is now lower than readState.Index' (duration: 391.368µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:19:54.13776Z","caller":"traceutil/trace.go:171","msg":"trace[855666998] transaction","detail":"{read_only:false; response_revision:3552; number_of_response:1; }","duration":"251.955603ms","start":"2026-08-05T07:19:53.885768Z","end":"2026-08-05T07:19:54.137723Z","steps":["trace[855666998] 'process raft request' (duration: 251.78487ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:19:54.137797Z","caller":"traceutil/trace.go:171","msg":"trace[1505907037] transaction","detail":"{read_only:false; response_revision:3551; number_of_response:1; }","duration":"285.801245ms","start":"2026-08-05T07:19:53.851967Z","end":"2026-08-05T07:19:54.137768Z","steps":["trace[1505907037] 'process raft request' (duration: 285.400257ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:19:54.137813Z","caller":"traceutil/trace.go:171","msg":"trace[1511249150] transaction","detail":"{read_only:false; response_revision:3553; number_of_response:1; }","duration":"245.94844ms","start":"2026-08-05T07:19:53.891799Z","end":"2026-08-05T07:19:54.137747Z","steps":["trace[1511249150] 'process raft request' (duration: 245.796337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:19:54.137964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.210519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nrrmg\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-05T07:19:54.138043Z","caller":"traceutil/trace.go:171","msg":"trace[1179814582] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nrrmg; range_end:; response_count:1; response_revision:3553; }","duration":"142.347242ms","start":"2026-08-05T07:19:53.995683Z","end":"2026-08-05T07:19:54.13803Z","steps":["trace[1179814582] 'agreement among raft nodes before linearized reading' (duration: 142.088556ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:21:21.897967Z","caller":"traceutil/trace.go:171","msg":"trace[509816582] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"160.571994ms","start":"2026-08-05T07:21:21.73737Z","end":"2026-08-05T07:21:21.897942Z","steps":["trace[509816582] 'process raft request' (duration: 160.315538ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:21:42.867906Z","caller":"traceutil/trace.go:171","msg":"trace[1090885681] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"113.009321ms","start":"2026-08-05T07:21:42.754853Z","end":"2026-08-05T07:21:42.867862Z","steps":["trace[1090885681] 'process raft request' (duration: 101.886463ms)","trace[1090885681] 'compare' (duration: 10.878303ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:21:43.277224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.970482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T07:21:43.27733Z","caller":"traceutil/trace.go:171","msg":"trace[293629849] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5159; }","duration":"119.136026ms","start":"2026-08-05T07:21:43.15816Z","end":"2026-08-05T07:21:43.277296Z","steps":["trace[293629849] 'count revisions from in-memory index tree' (duration: 118.88083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:21:43.277807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.114457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-05T07:21:43.277889Z","caller":"traceutil/trace.go:171","msg":"trace[1817265462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5159; }","duration":"300.238621ms","start":"2026-08-05T07:21:42.977633Z","end":"2026-08-05T07:21:43.277872Z","steps":["trace[1817265462] 'range keys from in-memory index tree' (duration: 299.834042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:21:43.277934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T07:21:42.977614Z","time spent":"300.307632ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-05T07:23:10.296545Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2815} {"level":"info","ts":"2026-08-05T07:23:10.359775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2815,"took":"61.857765ms","hash":1137806665,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":33386496,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-05T07:23:10.359897Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1137806665,"revision":2815,"compact-revision":-1} {"level":"info","ts":"2026-08-05T07:27:35.173796Z","caller":"traceutil/trace.go:171","msg":"trace[961277873] linearizableReadLoop","detail":"{readStateIndex:8211; appliedIndex:8210; }","duration":"178.036934ms","start":"2026-08-05T07:27:34.995732Z","end":"2026-08-05T07:27:35.173769Z","steps":["trace[961277873] 'read index received' (duration: 177.81695ms)","trace[961277873] 'applied index is now lower than readState.Index' (duration: 219.104µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:27:35.173842Z","caller":"traceutil/trace.go:171","msg":"trace[1482591479] transaction","detail":"{read_only:false; response_revision:7933; number_of_response:1; }","duration":"280.244756ms","start":"2026-08-05T07:27:34.893561Z","end":"2026-08-05T07:27:35.173805Z","steps":["trace[1482591479] 'process raft request' (duration: 280.043022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:27:35.174018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.262189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-05T07:27:35.174071Z","caller":"traceutil/trace.go:171","msg":"trace[162529565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7933; }","duration":"178.365751ms","start":"2026-08-05T07:27:34.995691Z","end":"2026-08-05T07:27:35.174056Z","steps":["trace[162529565] 'agreement among raft nodes before linearized reading' (duration: 178.189267ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:27:46.71613Z","caller":"traceutil/trace.go:171","msg":"trace[510809385] transaction","detail":"{read_only:false; response_revision:7992; number_of_response:1; }","duration":"105.09238ms","start":"2026-08-05T07:27:46.611015Z","end":"2026-08-05T07:27:46.716107Z","steps":["trace[510809385] 'process raft request' (duration: 33.569843ms)","trace[510809385] 'compare' (duration: 71.304832ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:27:46.716346Z","caller":"traceutil/trace.go:171","msg":"trace[1463537959] transaction","detail":"{read_only:false; response_revision:7993; number_of_response:1; }","duration":"104.441787ms","start":"2026-08-05T07:27:46.611868Z","end":"2026-08-05T07:27:46.71631Z","steps":["trace[1463537959] 'process raft request' (duration: 104.192942ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:27:47.99501Z","caller":"traceutil/trace.go:171","msg":"trace[680528939] transaction","detail":"{read_only:false; response_revision:7999; number_of_response:1; }","duration":"157.093958ms","start":"2026-08-05T07:27:47.837886Z","end":"2026-08-05T07:27:47.99498Z","steps":["trace[680528939] 'process raft request' (duration: 100.553578ms)","trace[680528939] 'compare' (duration: 56.371727ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:28:10.314454Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5954} {"level":"info","ts":"2026-08-05T07:28:10.410247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5954,"took":"93.446404ms","hash":2428079206,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":22573056,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-05T07:28:10.410332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2428079206,"revision":5954,"compact-revision":2815} {"level":"warn","ts":"2026-08-05T07:28:15.707146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.328174ms","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-08-05T07:28:15.707244Z","caller":"traceutil/trace.go:171","msg":"trace[262987262] transaction","detail":"{read_only:false; response_revision:8200; number_of_response:1; }","duration":"263.108717ms","start":"2026-08-05T07:28:15.444104Z","end":"2026-08-05T07:28:15.707213Z","steps":["trace[262987262] 'process raft request' (duration: 239.726881ms)","trace[262987262] 'compare' (duration: 23.011478ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:28:15.707286Z","caller":"traceutil/trace.go:171","msg":"trace[514252745] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8199; }","duration":"158.484617ms","start":"2026-08-05T07:28:15.548777Z","end":"2026-08-05T07:28:15.707262Z","steps":["trace[514252745] 'range keys from in-memory index tree' (duration: 158.305263ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:28:28.217159Z","caller":"traceutil/trace.go:171","msg":"trace[1245735653] transaction","detail":"{read_only:false; response_revision:8308; number_of_response:1; }","duration":"112.161121ms","start":"2026-08-05T07:28:28.104968Z","end":"2026-08-05T07:28:28.21713Z","steps":["trace[1245735653] 'process raft request' (duration: 109.703261ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:28:29.16004Z","caller":"traceutil/trace.go:171","msg":"trace[317347176] linearizableReadLoop","detail":"{readStateIndex:8609; appliedIndex:8608; }","duration":"215.230067ms","start":"2026-08-05T07:28:28.944763Z","end":"2026-08-05T07:28:29.159993Z","steps":["trace[317347176] 'read index received' (duration: 205.280974ms)","trace[317347176] 'applied index is now lower than readState.Index' (duration: 9.947363ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:28:29.160086Z","caller":"traceutil/trace.go:171","msg":"trace[1997456754] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"261.664271ms","start":"2026-08-05T07:28:28.898339Z","end":"2026-08-05T07:28:29.160003Z","steps":["trace[1997456754] 'process raft request' (duration: 251.717999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:28:29.160371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.579654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414319"} {"level":"info","ts":"2026-08-05T07:28:29.160481Z","caller":"traceutil/trace.go:171","msg":"trace[1866885405] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:8313; }","duration":"215.722847ms","start":"2026-08-05T07:28:28.944726Z","end":"2026-08-05T07:28:29.160449Z","steps":["trace[1866885405] 'agreement among raft nodes before linearized reading' (duration: 215.35119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:28:29.177183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.010394ms","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-08-05T07:28:29.177278Z","caller":"traceutil/trace.go:171","msg":"trace[981291022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8315; }","duration":"218.157686ms","start":"2026-08-05T07:28:28.959101Z","end":"2026-08-05T07:28:29.177258Z","steps":["trace[981291022] 'agreement among raft nodes before linearized reading' (duration: 217.906961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:28:29.177274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.645281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-q4jpm\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-08-05T07:28:29.177074Z","caller":"traceutil/trace.go:171","msg":"trace[1684879187] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"106.130689ms","start":"2026-08-05T07:28:29.070908Z","end":"2026-08-05T07:28:29.177039Z","steps":["trace[1684879187] 'process raft request' (duration: 106.023886ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:28:29.177356Z","caller":"traceutil/trace.go:171","msg":"trace[1348516924] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-q4jpm; range_end:; response_count:1; response_revision:8315; }","duration":"112.778792ms","start":"2026-08-05T07:28:29.064557Z","end":"2026-08-05T07:28:29.177336Z","steps":["trace[1348516924] 'agreement among raft nodes before linearized reading' (duration: 112.510187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:28:29.177293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.467862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-nrrmg\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-05T07:28:29.177434Z","caller":"traceutil/trace.go:171","msg":"trace[2040979404] range","detail":"{range_begin:/registry/pods/openstack/keepalived-nrrmg; range_end:; response_count:1; response_revision:8315; }","duration":"188.654986ms","start":"2026-08-05T07:28:28.988765Z","end":"2026-08-05T07:28:29.17742Z","steps":["trace[2040979404] 'agreement among raft nodes before linearized reading' (duration: 188.383391ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:28:29.177464Z","caller":"traceutil/trace.go:171","msg":"trace[1482136764] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"111.712662ms","start":"2026-08-05T07:28:29.065688Z","end":"2026-08-05T07:28:29.177401Z","steps":["trace[1482136764] 'process raft request' (duration: 111.065409ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:28:29.970862Z","caller":"traceutil/trace.go:171","msg":"trace[2129637848] linearizableReadLoop","detail":"{readStateIndex:8616; appliedIndex:8615; }","duration":"171.308923ms","start":"2026-08-05T07:28:29.799533Z","end":"2026-08-05T07:28:29.970842Z","steps":["trace[2129637848] 'read index received' (duration: 171.115129ms)","trace[2129637848] 'applied index is now lower than readState.Index' (duration: 193.184µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:28:29.970945Z","caller":"traceutil/trace.go:171","msg":"trace[318058564] transaction","detail":"{read_only:false; response_revision:8320; number_of_response:1; }","duration":"181.528641ms","start":"2026-08-05T07:28:29.789406Z","end":"2026-08-05T07:28:29.970935Z","steps":["trace[318058564] 'process raft request' (duration: 181.321237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:28:29.971099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.558458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19317"} {"level":"info","ts":"2026-08-05T07:28:29.971121Z","caller":"traceutil/trace.go:171","msg":"trace[654955216] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8320; }","duration":"171.6199ms","start":"2026-08-05T07:28:29.799496Z","end":"2026-08-05T07:28:29.971116Z","steps":["trace[654955216] 'agreement among raft nodes before linearized reading' (duration: 171.529388ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:28:29.971325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.369611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-05T07:28:29.971314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.867666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-08-05T07:28:29.971341Z","caller":"traceutil/trace.go:171","msg":"trace[1474229329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8320; }","duration":"101.417132ms","start":"2026-08-05T07:28:29.869919Z","end":"2026-08-05T07:28:29.971336Z","steps":["trace[1474229329] 'agreement among raft nodes before linearized reading' (duration: 101.370951ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:28:29.971365Z","caller":"traceutil/trace.go:171","msg":"trace[110895252] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8320; }","duration":"145.932627ms","start":"2026-08-05T07:28:29.825419Z","end":"2026-08-05T07:28:29.971351Z","steps":["trace[110895252] 'agreement among raft nodes before linearized reading' (duration: 145.753244ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:28:30.58205Z","caller":"traceutil/trace.go:171","msg":"trace[683470929] transaction","detail":"{read_only:false; response_revision:8327; number_of_response:1; }","duration":"107.011426ms","start":"2026-08-05T07:28:30.474983Z","end":"2026-08-05T07:28:30.581995Z","steps":["trace[683470929] 'process raft request' (duration: 106.70356ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:29:21.665724Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023cf.wal"} {"level":"info","ts":"2026-08-05T07:29:36.526724Z","caller":"traceutil/trace.go:171","msg":"trace[384346560] transaction","detail":"{read_only:false; response_revision:8953; number_of_response:1; }","duration":"147.047767ms","start":"2026-08-05T07:29:36.37962Z","end":"2026-08-05T07:29:36.526667Z","steps":["trace[384346560] 'process raft request' (duration: 106.794667ms)","trace[384346560] 'compare' (duration: 39.74714ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:31:02.60695Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T07:31:02.613419Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T07:31:02.61357Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T07:33:10.323408Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8125} {"level":"info","ts":"2026-08-05T07:33:10.384401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8125,"took":"58.77507ms","hash":2760621881,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":30994432,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T07:33:10.384484Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2760621881,"revision":8125,"compact-revision":5954} {"level":"info","ts":"2026-08-05T07:33:17.664106Z","caller":"traceutil/trace.go:171","msg":"trace[1535367497] transaction","detail":"{read_only:false; response_revision:10883; number_of_response:1; }","duration":"108.037387ms","start":"2026-08-05T07:33:17.556029Z","end":"2026-08-05T07:33:17.664066Z","steps":["trace[1535367497] 'process raft request' (duration: 100.36914ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:38:10.336468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10847} {"level":"info","ts":"2026-08-05T07:38:10.417833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10847,"took":"78.27121ms","hash":2083742780,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":27529216,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T07:38:10.417888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2083742780,"revision":10847,"compact-revision":8125} {"level":"info","ts":"2026-08-05T07:39:22.220079Z","caller":"traceutil/trace.go:171","msg":"trace[1523112797] linearizableReadLoop","detail":"{readStateIndex:13810; appliedIndex:13809; }","duration":"114.682364ms","start":"2026-08-05T07:39:22.105374Z","end":"2026-08-05T07:39:22.220056Z","steps":["trace[1523112797] 'read index received' (duration: 114.526591ms)","trace[1523112797] 'applied index is now lower than readState.Index' (duration: 154.753µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:39:22.220186Z","caller":"traceutil/trace.go:171","msg":"trace[1292697696] transaction","detail":"{read_only:false; response_revision:13355; number_of_response:1; }","duration":"121.199759ms","start":"2026-08-05T07:39:22.098943Z","end":"2026-08-05T07:39:22.220143Z","steps":["trace[1292697696] 'process raft request' (duration: 120.970864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:39:22.220289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.882389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T07:39:22.220334Z","caller":"traceutil/trace.go:171","msg":"trace[360144141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13355; }","duration":"115.000892ms","start":"2026-08-05T07:39:22.105321Z","end":"2026-08-05T07:39:22.220322Z","steps":["trace[360144141] 'agreement among raft nodes before linearized reading' (duration: 114.89837ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:41:24.927355Z","caller":"traceutil/trace.go:171","msg":"trace[1438088656] transaction","detail":"{read_only:false; response_revision:14565; number_of_response:1; }","duration":"130.468966ms","start":"2026-08-05T07:41:24.796844Z","end":"2026-08-05T07:41:24.927313Z","steps":["trace[1438088656] 'process raft request' (duration: 130.243482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:41:25.888115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.739376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T07:41:25.888179Z","caller":"traceutil/trace.go:171","msg":"trace[327373697] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14570; }","duration":"185.819448ms","start":"2026-08-05T07:41:25.702343Z","end":"2026-08-05T07:41:25.888162Z","steps":["trace[327373697] 'range keys from in-memory index tree' (duration: 185.707926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:41:25.888287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.007262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T07:41:25.888304Z","caller":"traceutil/trace.go:171","msg":"trace[409845093] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14570; }","duration":"186.025642ms","start":"2026-08-05T07:41:25.702273Z","end":"2026-08-05T07:41:25.888299Z","steps":["trace[409845093] 'range keys from in-memory index tree' (duration: 185.849539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:41:25.888352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.93446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T07:41:25.888371Z","caller":"traceutil/trace.go:171","msg":"trace[998647326] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14570; }","duration":"185.9609ms","start":"2026-08-05T07:41:25.702404Z","end":"2026-08-05T07:41:25.888365Z","steps":["trace[998647326] 'range keys from in-memory index tree' (duration: 185.90045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:41:25.888604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.988121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-05T07:41:25.888629Z","caller":"traceutil/trace.go:171","msg":"trace[1815511129] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14570; }","duration":"186.021863ms","start":"2026-08-05T07:41:25.7026Z","end":"2026-08-05T07:41:25.888622Z","steps":["trace[1815511129] 'range keys from in-memory index tree' (duration: 185.89649ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:43:10.344295Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12875} {"level":"info","ts":"2026-08-05T07:43:10.436588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12875,"took":"89.012449ms","hash":111159814,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T07:43:10.436644Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":111159814,"revision":12875,"compact-revision":10847} {"level":"info","ts":"2026-08-05T07:46:48.296573Z","caller":"traceutil/trace.go:171","msg":"trace[533136472] linearizableReadLoop","detail":"{readStateIndex:17591; appliedIndex:17590; }","duration":"274.362112ms","start":"2026-08-05T07:46:48.022172Z","end":"2026-08-05T07:46:48.296534Z","steps":["trace[533136472] 'read index received' (duration: 273.995975ms)","trace[533136472] 'applied index is now lower than readState.Index' (duration: 364.937µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:46:48.296683Z","caller":"traceutil/trace.go:171","msg":"trace[2121858659] transaction","detail":"{read_only:false; response_revision:17019; number_of_response:1; }","duration":"276.022016ms","start":"2026-08-05T07:46:48.020612Z","end":"2026-08-05T07:46:48.296634Z","steps":["trace[2121858659] 'process raft request' (duration: 275.695969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:46:48.335716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.518493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-08-05T07:46:48.33581Z","caller":"traceutil/trace.go:171","msg":"trace[828990234] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:17019; }","duration":"313.615745ms","start":"2026-08-05T07:46:48.022168Z","end":"2026-08-05T07:46:48.335784Z","steps":["trace[828990234] 'agreement among raft nodes before linearized reading' (duration: 274.571716ms)","trace[828990234] 'range keys from in-memory index tree' (duration: 38.883666ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:46:48.33585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T07:46:48.022146Z","time spent":"313.694337ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T07:46:48.336313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.488634ms","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-08-05T07:46:48.336387Z","caller":"traceutil/trace.go:171","msg":"trace[1762947474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17019; }","duration":"295.608177ms","start":"2026-08-05T07:46:48.040758Z","end":"2026-08-05T07:46:48.336366Z","steps":["trace[1762947474] 'agreement among raft nodes before linearized reading' (duration: 256.010657ms)","trace[1762947474] 'range keys from in-memory index tree' (duration: 39.414346ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:48:10.352249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15313} {"level":"info","ts":"2026-08-05T07:48:10.45829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15313,"took":"101.075548ms","hash":195661115,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":33366016,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-05T07:48:10.458378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":195661115,"revision":15313,"compact-revision":12875} {"level":"info","ts":"2026-08-05T07:50:47.175556Z","caller":"traceutil/trace.go:171","msg":"trace[1314600779] linearizableReadLoop","detail":"{readStateIndex:19923; appliedIndex:19922; }","duration":"119.732207ms","start":"2026-08-05T07:50:47.055782Z","end":"2026-08-05T07:50:47.175515Z","steps":["trace[1314600779] 'read index received' (duration: 119.38929ms)","trace[1314600779] 'applied index is now lower than readState.Index' (duration: 340.647µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:50:47.175609Z","caller":"traceutil/trace.go:171","msg":"trace[389819321] transaction","detail":"{read_only:false; response_revision:19286; number_of_response:1; }","duration":"120.454542ms","start":"2026-08-05T07:50:47.055099Z","end":"2026-08-05T07:50:47.175554Z","steps":["trace[389819321] 'process raft request' (duration: 120.111135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:50:47.177273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.962702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T07:50:47.178597Z","caller":"traceutil/trace.go:171","msg":"trace[916332689] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:19286; }","duration":"122.668717ms","start":"2026-08-05T07:50:47.055766Z","end":"2026-08-05T07:50:47.178435Z","steps":["trace[916332689] 'agreement among raft nodes before linearized reading' (duration: 119.88956ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:50:56.086917Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T07:50:56.094474Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T07:50:56.094575Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-05T07:53:10.362882Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17890} {"level":"info","ts":"2026-08-05T07:53:10.48102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17890,"took":"113.084702ms","hash":2786630670,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":31756288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T07:53:10.481084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2786630670,"revision":17890,"compact-revision":15313} {"level":"info","ts":"2026-08-05T07:54:22.445427Z","caller":"traceutil/trace.go:171","msg":"trace[835284330] transaction","detail":"{read_only:false; response_revision:20768; number_of_response:1; }","duration":"244.601977ms","start":"2026-08-05T07:54:22.200801Z","end":"2026-08-05T07:54:22.445403Z","steps":["trace[835284330] 'process raft request' (duration: 244.382963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:54:22.714449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.918002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T07:54:22.714579Z","caller":"traceutil/trace.go:171","msg":"trace[890918438] linearizableReadLoop","detail":"{readStateIndex:21459; appliedIndex:21458; }","duration":"143.526632ms","start":"2026-08-05T07:54:22.571028Z","end":"2026-08-05T07:54:22.714555Z","steps":["trace[890918438] 'read index received' (duration: 54.911µs)","trace[890918438] 'applied index is now lower than readState.Index' (duration: 143.470001ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:54:22.714664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.648705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T07:54:22.714699Z","caller":"traceutil/trace.go:171","msg":"trace[1830088073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20769; }","duration":"143.736777ms","start":"2026-08-05T07:54:22.570952Z","end":"2026-08-05T07:54:22.714689Z","steps":["trace[1830088073] 'agreement among raft nodes before linearized reading' (duration: 143.662625ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:54:22.714735Z","caller":"traceutil/trace.go:171","msg":"trace[1520248480] transaction","detail":"{read_only:false; response_revision:20769; number_of_response:1; }","duration":"390.541148ms","start":"2026-08-05T07:54:22.324081Z","end":"2026-08-05T07:54:22.714623Z","steps":["trace[1520248480] 'process raft request' (duration: 242.3254ms)","trace[1520248480] 'compare' (duration: 147.710278ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:54:22.715142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T07:54:22.324049Z","time spent":"390.953747ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T07:54:23.017316Z","caller":"traceutil/trace.go:171","msg":"trace[914146011] transaction","detail":"{read_only:false; response_revision:20770; number_of_response:1; }","duration":"101.237108ms","start":"2026-08-05T07:54:22.916035Z","end":"2026-08-05T07:54:23.017272Z","steps":["trace[914146011] 'process raft request' (duration: 100.978603ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:54:23.218787Z","caller":"traceutil/trace.go:171","msg":"trace[753134043] linearizableReadLoop","detail":"{readStateIndex:21461; appliedIndex:21460; }","duration":"151.21379ms","start":"2026-08-05T07:54:23.067537Z","end":"2026-08-05T07:54:23.218751Z","steps":["trace[753134043] 'read index received' (duration: 109.878125ms)","trace[753134043] 'applied index is now lower than readState.Index' (duration: 41.334475ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:54:23.218815Z","caller":"traceutil/trace.go:171","msg":"trace[1592301] transaction","detail":"{read_only:false; response_revision:20771; number_of_response:1; }","duration":"156.535508ms","start":"2026-08-05T07:54:23.062236Z","end":"2026-08-05T07:54:23.218772Z","steps":["trace[1592301] 'process raft request' (duration: 115.195603ms)","trace[1592301] 'compare' (duration: 41.07852ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:54:23.219139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.074142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-08-05T07:54:23.219214Z","caller":"traceutil/trace.go:171","msg":"trace[88011957] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20772; }","duration":"139.167194ms","start":"2026-08-05T07:54:23.080026Z","end":"2026-08-05T07:54:23.219193Z","steps":["trace[88011957] 'agreement among raft nodes before linearized reading' (duration: 138.944309ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:54:23.219138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.77142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-05T07:54:23.219293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.72408ms","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-08-05T07:54:23.219345Z","caller":"traceutil/trace.go:171","msg":"trace[1306990248] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:20772; }","duration":"101.025384ms","start":"2026-08-05T07:54:23.11829Z","end":"2026-08-05T07:54:23.219315Z","steps":["trace[1306990248] 'agreement among raft nodes before linearized reading' (duration: 100.721588ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:54:23.219421Z","caller":"traceutil/trace.go:171","msg":"trace[816817211] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20772; }","duration":"151.934004ms","start":"2026-08-05T07:54:23.067453Z","end":"2026-08-05T07:54:23.219387Z","steps":["trace[816817211] 'agreement among raft nodes before linearized reading' (duration: 151.403473ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:54:23.443479Z","caller":"traceutil/trace.go:171","msg":"trace[814025390] transaction","detail":"{read_only:false; response_revision:20773; number_of_response:1; }","duration":"218.645308ms","start":"2026-08-05T07:54:23.224803Z","end":"2026-08-05T07:54:23.443448Z","steps":["trace[814025390] 'process raft request' (duration: 218.444504ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:54:23.489993Z","caller":"traceutil/trace.go:171","msg":"trace[430461503] linearizableReadLoop","detail":"{readStateIndex:21464; appliedIndex:21462; }","duration":"192.527774ms","start":"2026-08-05T07:54:23.297418Z","end":"2026-08-05T07:54:23.489946Z","steps":["trace[430461503] 'read index received' (duration: 145.83669ms)","trace[430461503] 'applied index is now lower than readState.Index' (duration: 46.689354ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:54:23.490438Z","caller":"traceutil/trace.go:171","msg":"trace[1421379738] transaction","detail":"{read_only:false; response_revision:20774; number_of_response:1; }","duration":"265.229889ms","start":"2026-08-05T07:54:23.225183Z","end":"2026-08-05T07:54:23.490413Z","steps":["trace[1421379738] 'process raft request' (duration: 264.579676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:54:23.490829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.375791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-05T07:54:23.490895Z","caller":"traceutil/trace.go:171","msg":"trace[1051385202] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20774; }","duration":"193.515653ms","start":"2026-08-05T07:54:23.29736Z","end":"2026-08-05T07:54:23.490875Z","steps":["trace[1051385202] 'agreement among raft nodes before linearized reading' (duration: 193.33121ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:54:23.676244Z","caller":"traceutil/trace.go:171","msg":"trace[1374079885] transaction","detail":"{read_only:false; response_revision:20775; number_of_response:1; }","duration":"180.14129ms","start":"2026-08-05T07:54:23.496059Z","end":"2026-08-05T07:54:23.6762Z","steps":["trace[1374079885] 'process raft request' (duration: 179.884865ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:54:23.676732Z","caller":"traceutil/trace.go:171","msg":"trace[1990198143] linearizableReadLoop","detail":"{readStateIndex:21467; appliedIndex:21464; }","duration":"143.082633ms","start":"2026-08-05T07:54:23.53359Z","end":"2026-08-05T07:54:23.676672Z","steps":["trace[1990198143] 'read index received' (duration: 58.300731ms)","trace[1990198143] 'applied index is now lower than readState.Index' (duration: 84.779312ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:54:23.676774Z","caller":"traceutil/trace.go:171","msg":"trace[1337650986] transaction","detail":"{read_only:false; response_revision:20776; number_of_response:1; }","duration":"180.168061ms","start":"2026-08-05T07:54:23.49657Z","end":"2026-08-05T07:54:23.676738Z","steps":["trace[1337650986] 'process raft request' (duration: 179.577899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:54:23.677011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.830154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T07:54:23.67706Z","caller":"traceutil/trace.go:171","msg":"trace[1727463911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20776; }","duration":"106.924886ms","start":"2026-08-05T07:54:23.570123Z","end":"2026-08-05T07:54:23.677048Z","steps":["trace[1727463911] 'agreement among raft nodes before linearized reading' (duration: 106.827064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T07:54:23.677228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.488282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T07:54:23.677291Z","caller":"traceutil/trace.go:171","msg":"trace[864202894] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:20776; }","duration":"143.692047ms","start":"2026-08-05T07:54:23.533582Z","end":"2026-08-05T07:54:23.677274Z","steps":["trace[864202894] 'agreement among raft nodes before linearized reading' (duration: 143.321178ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:54:23.86973Z","caller":"traceutil/trace.go:171","msg":"trace[946957011] transaction","detail":"{read_only:false; response_revision:20777; number_of_response:1; }","duration":"191.839519ms","start":"2026-08-05T07:54:23.677854Z","end":"2026-08-05T07:54:23.869693Z","steps":["trace[946957011] 'process raft request' (duration: 100.906082ms)","trace[946957011] 'compare' (duration: 90.762984ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T07:54:23.869779Z","caller":"traceutil/trace.go:171","msg":"trace[1356005730] transaction","detail":"{read_only:false; response_revision:20778; number_of_response:1; }","duration":"187.628963ms","start":"2026-08-05T07:54:23.68212Z","end":"2026-08-05T07:54:23.869749Z","steps":["trace[1356005730] 'process raft request' (duration: 187.581522ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:54:23.869822Z","caller":"traceutil/trace.go:171","msg":"trace[687913158] linearizableReadLoop","detail":"{readStateIndex:21468; appliedIndex:21467; }","duration":"190.89792ms","start":"2026-08-05T07:54:23.678887Z","end":"2026-08-05T07:54:23.869785Z","steps":["trace[687913158] 'read index received' (duration: 99.920432ms)","trace[687913158] 'applied index is now lower than readState.Index' (duration: 90.972858ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T07:54:23.870008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.096014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T07:54:23.870078Z","caller":"traceutil/trace.go:171","msg":"trace[1389034399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20778; }","duration":"191.239557ms","start":"2026-08-05T07:54:23.678823Z","end":"2026-08-05T07:54:23.870062Z","steps":["trace[1389034399] 'agreement among raft nodes before linearized reading' (duration: 191.114564ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T07:58:10.368277Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20365} {"level":"info","ts":"2026-08-05T07:58:10.479919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20365,"took":"107.73249ms","hash":1021531250,"current-db-size-bytes":57487360,"current-db-size":"58 MB","current-db-size-in-use-bytes":43184128,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-05T07:58:10.479983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1021531250,"revision":20365,"compact-revision":17890} {"level":"info","ts":"2026-08-05T08:01:02.477151Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006533.wal"} {"level":"info","ts":"2026-08-05T08:01:08.502847Z","caller":"traceutil/trace.go:171","msg":"trace[721424686] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"112.836355ms","start":"2026-08-05T08:01:08.389975Z","end":"2026-08-05T08:01:08.502812Z","steps":["trace[721424686] 'process raft request' (duration: 112.544049ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:14.018222Z","caller":"traceutil/trace.go:171","msg":"trace[519600359] linearizableReadLoop","detail":"{readStateIndex:26054; appliedIndex:26053; }","duration":"144.92542ms","start":"2026-08-05T08:01:13.873272Z","end":"2026-08-05T08:01:14.018198Z","steps":["trace[519600359] 'read index received' (duration: 144.786637ms)","trace[519600359] 'applied index is now lower than readState.Index' (duration: 137.143µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:14.018315Z","caller":"traceutil/trace.go:171","msg":"trace[97599899] transaction","detail":"{read_only:false; response_revision:25240; number_of_response:1; }","duration":"157.513577ms","start":"2026-08-05T08:01:13.86075Z","end":"2026-08-05T08:01:14.018264Z","steps":["trace[97599899] 'process raft request' (duration: 157.292723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:14.018417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.125194ms","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-08-05T08:01:14.018448Z","caller":"traceutil/trace.go:171","msg":"trace[452040089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25240; }","duration":"145.210716ms","start":"2026-08-05T08:01:13.873229Z","end":"2026-08-05T08:01:14.01844Z","steps":["trace[452040089] 'agreement among raft nodes before linearized reading' (duration: 145.086004ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:14.107728Z","caller":"traceutil/trace.go:171","msg":"trace[1268624530] transaction","detail":"{read_only:false; response_revision:25241; number_of_response:1; }","duration":"134.606269ms","start":"2026-08-05T08:01:13.973073Z","end":"2026-08-05T08:01:14.107679Z","steps":["trace[1268624530] 'process raft request' (duration: 132.506517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:14.107958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.223339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:14.108061Z","caller":"traceutil/trace.go:171","msg":"trace[238997125] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25242; }","duration":"220.402223ms","start":"2026-08-05T08:01:13.887637Z","end":"2026-08-05T08:01:14.108039Z","steps":["trace[238997125] 'agreement among raft nodes before linearized reading' (duration: 220.138996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:14.480474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.882141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:01:14.481007Z","caller":"traceutil/trace.go:171","msg":"trace[2032233821] linearizableReadLoop","detail":"{readStateIndex:26058; appliedIndex:26056; }","duration":"362.898534ms","start":"2026-08-05T08:01:14.11809Z","end":"2026-08-05T08:01:14.480989Z","steps":["trace[2032233821] 'read index received' (duration: 142.541652ms)","trace[2032233821] 'applied index is now lower than readState.Index' (duration: 220.355282ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:14.481076Z","caller":"traceutil/trace.go:171","msg":"trace[804174215] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"365.69025ms","start":"2026-08-05T08:01:14.115374Z","end":"2026-08-05T08:01:14.481064Z","steps":["trace[804174215] 'process raft request' (duration: 365.510527ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:14.480988Z","caller":"traceutil/trace.go:171","msg":"trace[1401086118] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"367.965397ms","start":"2026-08-05T08:01:14.112956Z","end":"2026-08-05T08:01:14.480921Z","steps":["trace[1401086118] 'process raft request' (duration: 147.480482ms)","trace[1401086118] 'compare' (duration: 219.626506ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:14.481198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:14.115351Z","time spent":"365.774912ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:01:14.48132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.22209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-05T08:01:14.481377Z","caller":"traceutil/trace.go:171","msg":"trace[22727682] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25244; }","duration":"363.339522ms","start":"2026-08-05T08:01:14.118024Z","end":"2026-08-05T08:01:14.481363Z","steps":["trace[22727682] 'agreement among raft nodes before linearized reading' (duration: 363.119528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:14.481372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:14.112932Z","time spent":"368.306944ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:01:14.481453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:14.118011Z","time spent":"363.429664ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-05T08:01:14.48147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.713972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-05T08:01:14.481555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.070164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-08-05T08:01:14.481569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.587019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:01:14.481591Z","caller":"traceutil/trace.go:171","msg":"trace[972611657] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25244; }","duration":"322.902335ms","start":"2026-08-05T08:01:14.158656Z","end":"2026-08-05T08:01:14.481559Z","steps":["trace[972611657] 'agreement among raft nodes before linearized reading' (duration: 322.561829ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:14.481643Z","caller":"traceutil/trace.go:171","msg":"trace[459625164] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25244; }","duration":"321.691382ms","start":"2026-08-05T08:01:14.15993Z","end":"2026-08-05T08:01:14.481621Z","steps":["trace[459625164] 'agreement among raft nodes before linearized reading' (duration: 321.564899ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:14.481651Z","caller":"traceutil/trace.go:171","msg":"trace[573617527] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25244; }","duration":"170.223017ms","start":"2026-08-05T08:01:14.31141Z","end":"2026-08-05T08:01:14.481633Z","steps":["trace[573617527] 'agreement among raft nodes before linearized reading' (duration: 169.993312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:14.481698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:14.159906Z","time spent":"321.781823ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T08:01:14.48165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:14.158632Z","time spent":"323.009198ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T08:01:14.481446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.925728ms","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-08-05T08:01:14.481828Z","caller":"traceutil/trace.go:171","msg":"trace[2013474410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25244; }","duration":"302.397638ms","start":"2026-08-05T08:01:14.179419Z","end":"2026-08-05T08:01:14.481816Z","steps":["trace[2013474410] 'agreement among raft nodes before linearized reading' (duration: 301.878007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:14.481867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:14.179406Z","time spent":"302.452298ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-05T08:01:14.604567Z","caller":"traceutil/trace.go:171","msg":"trace[2030899974] linearizableReadLoop","detail":"{readStateIndex:26059; appliedIndex:26058; }","duration":"117.53511ms","start":"2026-08-05T08:01:14.486908Z","end":"2026-08-05T08:01:14.604443Z","steps":["trace[2030899974] 'read index received' (duration: 114.115901ms)","trace[2030899974] 'applied index is now lower than readState.Index' (duration: 3.415699ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:14.604586Z","caller":"traceutil/trace.go:171","msg":"trace[128737121] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"118.035491ms","start":"2026-08-05T08:01:14.486514Z","end":"2026-08-05T08:01:14.60455Z","steps":["trace[128737121] 'process raft request' (duration: 114.56323ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:14.604846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.905088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17940"} {"level":"info","ts":"2026-08-05T08:01:14.604951Z","caller":"traceutil/trace.go:171","msg":"trace[1816147923] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25245; }","duration":"118.035971ms","start":"2026-08-05T08:01:14.486889Z","end":"2026-08-05T08:01:14.604925Z","steps":["trace[1816147923] 'agreement among raft nodes before linearized reading' (duration: 117.730215ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:18.287284Z","caller":"traceutil/trace.go:171","msg":"trace[962160661] linearizableReadLoop","detail":"{readStateIndex:26084; appliedIndex:26083; }","duration":"165.534141ms","start":"2026-08-05T08:01:18.121694Z","end":"2026-08-05T08:01:18.287228Z","steps":["trace[962160661] 'read index received' (duration: 156.341614ms)","trace[962160661] 'applied index is now lower than readState.Index' (duration: 9.191317ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:18.287808Z","caller":"traceutil/trace.go:171","msg":"trace[1035789851] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"166.210996ms","start":"2026-08-05T08:01:18.121539Z","end":"2026-08-05T08:01:18.28775Z","steps":["trace[1035789851] 'process raft request' (duration: 156.510127ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:18.287881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.145134ms","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":"warn","ts":"2026-08-05T08:01:18.287895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.050995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:18.287996Z","caller":"traceutil/trace.go:171","msg":"trace[2004834581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25270; }","duration":"166.326828ms","start":"2026-08-05T08:01:18.121635Z","end":"2026-08-05T08:01:18.287962Z","steps":["trace[2004834581] 'agreement among raft nodes before linearized reading' (duration: 165.963091ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:18.288007Z","caller":"traceutil/trace.go:171","msg":"trace[1447901718] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25270; }","duration":"103.238048ms","start":"2026-08-05T08:01:18.184736Z","end":"2026-08-05T08:01:18.287974Z","steps":["trace[1447901718] 'agreement among raft nodes before linearized reading' (duration: 103.040484ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:18.690674Z","caller":"traceutil/trace.go:171","msg":"trace[1454073383] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"187.303325ms","start":"2026-08-05T08:01:18.503332Z","end":"2026-08-05T08:01:18.690636Z","steps":["trace[1454073383] 'process raft request' (duration: 187.095731ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:18.99278Z","caller":"traceutil/trace.go:171","msg":"trace[1109242615] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"488.798574ms","start":"2026-08-05T08:01:18.503937Z","end":"2026-08-05T08:01:18.992736Z","steps":["trace[1109242615] 'process raft request' (duration: 458.640688ms)","trace[1109242615] 'compare' (duration: 29.638886ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:18.992999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:18.503906Z","time spent":"488.976888ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:01:18.996031Z","caller":"traceutil/trace.go:171","msg":"trace[622597297] linearizableReadLoop","detail":"{readStateIndex:26087; appliedIndex:26085; }","duration":"464.545939ms","start":"2026-08-05T08:01:18.531445Z","end":"2026-08-05T08:01:18.995991Z","steps":["trace[622597297] 'read index received' (duration: 159.011317ms)","trace[622597297] 'applied index is now lower than readState.Index' (duration: 305.532912ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:18.996224Z","caller":"traceutil/trace.go:171","msg":"trace[113824006] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"373.065261ms","start":"2026-08-05T08:01:18.623141Z","end":"2026-08-05T08:01:18.996206Z","steps":["trace[113824006] 'process raft request' (duration: 372.617882ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:18.9962Z","caller":"traceutil/trace.go:171","msg":"trace[1671005650] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"133.842853ms","start":"2026-08-05T08:01:18.862329Z","end":"2026-08-05T08:01:18.996172Z","steps":["trace[1671005650] 'process raft request' (duration: 133.620299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:18.996413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:18.623108Z","time spent":"373.175323ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:01:18.996584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.88554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:18.996613Z","caller":"traceutil/trace.go:171","msg":"trace[407657049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25275; }","duration":"425.961891ms","start":"2026-08-05T08:01:18.570642Z","end":"2026-08-05T08:01:18.996604Z","steps":["trace[407657049] 'agreement among raft nodes before linearized reading' (duration: 425.89347ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:18.996634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:18.570623Z","time spent":"426.005783ms","remote":"127.0.0.1:49688","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-08-05T08:01:18.996671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.156174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T08:01:18.996754Z","caller":"traceutil/trace.go:171","msg":"trace[1423661904] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25275; }","duration":"374.254816ms","start":"2026-08-05T08:01:18.62248Z","end":"2026-08-05T08:01:18.996735Z","steps":["trace[1423661904] 'agreement among raft nodes before linearized reading' (duration: 374.101253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:18.996786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:18.622446Z","time spent":"374.333847ms","remote":"127.0.0.1:50148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":20,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:01:18.996831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.660046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:01:18.996853Z","caller":"traceutil/trace.go:171","msg":"trace[1198038256] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25275; }","duration":"380.725128ms","start":"2026-08-05T08:01:18.61612Z","end":"2026-08-05T08:01:18.996846Z","steps":["trace[1198038256] 'agreement among raft nodes before linearized reading' (duration: 380.624806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:18.996874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:18.616101Z","time spent":"380.765278ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T08:01:18.996953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.537149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:18.996971Z","caller":"traceutil/trace.go:171","msg":"trace[624863576] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25275; }","duration":"465.613791ms","start":"2026-08-05T08:01:18.531351Z","end":"2026-08-05T08:01:18.996965Z","steps":["trace[624863576] 'agreement among raft nodes before linearized reading' (duration: 465.581411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:18.996988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:18.531337Z","time spent":"465.645962ms","remote":"127.0.0.1:45730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:01:18.997061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.368388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-05T08:01:18.997111Z","caller":"traceutil/trace.go:171","msg":"trace[627805396] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25275; }","duration":"421.440589ms","start":"2026-08-05T08:01:18.575655Z","end":"2026-08-05T08:01:18.997096Z","steps":["trace[627805396] 'agreement among raft nodes before linearized reading' (duration: 421.139223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:18.997147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:18.575642Z","time spent":"421.495691ms","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":238,"response size":32,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:01:18.997154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.912669ms","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-08-05T08:01:18.997202Z","caller":"traceutil/trace.go:171","msg":"trace[257039478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25275; }","duration":"373.98179ms","start":"2026-08-05T08:01:18.623214Z","end":"2026-08-05T08:01:18.997196Z","steps":["trace[257039478] 'agreement among raft nodes before linearized reading' (duration: 373.854867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:18.997216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:18.623204Z","time spent":"374.00834ms","remote":"127.0.0.1:49928","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-08-05T08:01:19.362536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.795304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:01:19.362792Z","caller":"traceutil/trace.go:171","msg":"trace[1502696037] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"201.963386ms","start":"2026-08-05T08:01:19.160799Z","end":"2026-08-05T08:01:19.362762Z","steps":["trace[1502696037] 'process raft request' (duration: 201.889824ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:19.362978Z","caller":"traceutil/trace.go:171","msg":"trace[438492688] linearizableReadLoop","detail":"{readStateIndex:26092; appliedIndex:26091; }","duration":"225.603788ms","start":"2026-08-05T08:01:19.13736Z","end":"2026-08-05T08:01:19.362964Z","steps":["trace[438492688] 'read index received' (duration: 54.551µs)","trace[438492688] 'applied index is now lower than readState.Index' (duration: 225.547457ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:19.362932Z","caller":"traceutil/trace.go:171","msg":"trace[123008756] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"347.936827ms","start":"2026-08-05T08:01:19.01495Z","end":"2026-08-05T08:01:19.362887Z","steps":["trace[123008756] 'process raft request' (duration: 93.630383ms)","trace[123008756] 'compare' (duration: 253.6103ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:19.363179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:19.014923Z","time spent":"348.133161ms","remote":"127.0.0.1:49928","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-08-05T08:01:19.36323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.837263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-08-05T08:01:19.363433Z","caller":"traceutil/trace.go:171","msg":"trace[1903870056] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25278; }","duration":"226.104759ms","start":"2026-08-05T08:01:19.137294Z","end":"2026-08-05T08:01:19.363398Z","steps":["trace[1903870056] 'agreement among raft nodes before linearized reading' (duration: 225.772582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:19.363489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.179161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-08-05T08:01:19.363644Z","caller":"traceutil/trace.go:171","msg":"trace[1818743976] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25278; }","duration":"132.401776ms","start":"2026-08-05T08:01:19.231207Z","end":"2026-08-05T08:01:19.363608Z","steps":["trace[1818743976] 'agreement among raft nodes before linearized reading' (duration: 131.941975ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:19.602007Z","caller":"traceutil/trace.go:171","msg":"trace[1081066357] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"232.810946ms","start":"2026-08-05T08:01:19.369165Z","end":"2026-08-05T08:01:19.601976Z","steps":["trace[1081066357] 'process raft request' (duration: 232.599592ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:19.704978Z","caller":"traceutil/trace.go:171","msg":"trace[1084459139] linearizableReadLoop","detail":"{readStateIndex:26095; appliedIndex:26093; }","duration":"310.030502ms","start":"2026-08-05T08:01:19.394912Z","end":"2026-08-05T08:01:19.704942Z","steps":["trace[1084459139] 'read index received' (duration: 206.918267ms)","trace[1084459139] 'applied index is now lower than readState.Index' (duration: 103.110615ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:19.705441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.494272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:19.705457Z","caller":"traceutil/trace.go:171","msg":"trace[1067428228] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"335.463903ms","start":"2026-08-05T08:01:19.369946Z","end":"2026-08-05T08:01:19.70541Z","steps":["trace[1067428228] 'process raft request' (duration: 332.724457ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:19.705598Z","caller":"traceutil/trace.go:171","msg":"trace[1813724463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25281; }","duration":"310.640215ms","start":"2026-08-05T08:01:19.394885Z","end":"2026-08-05T08:01:19.705526Z","steps":["trace[1813724463] 'agreement among raft nodes before linearized reading' (duration: 310.41248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:19.705654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:19.39487Z","time spent":"310.775168ms","remote":"127.0.0.1:49676","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-08-05T08:01:19.705628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.468144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:19.705637Z","caller":"traceutil/trace.go:171","msg":"trace[1299167560] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"225.970016ms","start":"2026-08-05T08:01:19.479626Z","end":"2026-08-05T08:01:19.705596Z","steps":["trace[1299167560] 'process raft request' (duration: 225.245431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:19.705676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:19.36991Z","time spent":"335.644427ms","remote":"127.0.0.1:49928","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-08-05T08:01:19.705709Z","caller":"traceutil/trace.go:171","msg":"trace[1119608075] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:25281; }","duration":"245.623237ms","start":"2026-08-05T08:01:19.460074Z","end":"2026-08-05T08:01:19.705698Z","steps":["trace[1119608075] 'agreement among raft nodes before linearized reading' (duration: 245.447094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:19.705779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.193332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-05T08:01:19.705814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.163066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-05T08:01:19.705893Z","caller":"traceutil/trace.go:171","msg":"trace[1284760301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25281; }","duration":"300.348095ms","start":"2026-08-05T08:01:19.405522Z","end":"2026-08-05T08:01:19.70587Z","steps":["trace[1284760301] 'agreement among raft nodes before linearized reading' (duration: 300.003998ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:19.705925Z","caller":"traceutil/trace.go:171","msg":"trace[834138182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25281; }","duration":"215.342758ms","start":"2026-08-05T08:01:19.490553Z","end":"2026-08-05T08:01:19.705896Z","steps":["trace[834138182] 'agreement among raft nodes before linearized reading' (duration: 214.978531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:19.705959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:19.40551Z","time spent":"300.436246ms","remote":"127.0.0.1:49928","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-08-05T08:01:20.019437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.302775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:20.019566Z","caller":"traceutil/trace.go:171","msg":"trace[917982404] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25283; }","duration":"208.489079ms","start":"2026-08-05T08:01:19.811045Z","end":"2026-08-05T08:01:20.019534Z","steps":["trace[917982404] 'range keys from in-memory index tree' (duration: 208.116292ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:20.151583Z","caller":"traceutil/trace.go:171","msg":"trace[89090827] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"102.411823ms","start":"2026-08-05T08:01:20.049137Z","end":"2026-08-05T08:01:20.151549Z","steps":["trace[89090827] 'process raft request' (duration: 101.950274ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:20.536544Z","caller":"traceutil/trace.go:171","msg":"trace[975530718] linearizableReadLoop","detail":"{readStateIndex:26100; appliedIndex:26099; }","duration":"226.538388ms","start":"2026-08-05T08:01:20.309974Z","end":"2026-08-05T08:01:20.536512Z","steps":["trace[975530718] 'read index received' (duration: 226.248262ms)","trace[975530718] 'applied index is now lower than readState.Index' (duration: 288.716µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:20.536608Z","caller":"traceutil/trace.go:171","msg":"trace[1010504395] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"241.757468ms","start":"2026-08-05T08:01:20.2948Z","end":"2026-08-05T08:01:20.536557Z","steps":["trace[1010504395] 'process raft request' (duration: 241.422651ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:20.536902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.895455ms","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-08-05T08:01:20.536996Z","caller":"traceutil/trace.go:171","msg":"trace[587246627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25285; }","duration":"227.015267ms","start":"2026-08-05T08:01:20.309956Z","end":"2026-08-05T08:01:20.536971Z","steps":["trace[587246627] 'agreement among raft nodes before linearized reading' (duration: 226.689451ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:21.117446Z","caller":"traceutil/trace.go:171","msg":"trace[1785695733] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"116.451509ms","start":"2026-08-05T08:01:21.000952Z","end":"2026-08-05T08:01:21.117404Z","steps":["trace[1785695733] 'process raft request' (duration: 116.213335ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:21.40213Z","caller":"traceutil/trace.go:171","msg":"trace[1602098617] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"400.228566ms","start":"2026-08-05T08:01:21.00184Z","end":"2026-08-05T08:01:21.402069Z","steps":["trace[1602098617] 'process raft request' (duration: 385.71837ms)","trace[1602098617] 'compare' (duration: 14.095598ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:21.402147Z","caller":"traceutil/trace.go:171","msg":"trace[260172937] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"398.567452ms","start":"2026-08-05T08:01:21.003541Z","end":"2026-08-05T08:01:21.402108Z","steps":["trace[260172937] 'process raft request' (duration: 398.410569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:21.402326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:21.001822Z","time spent":"400.401419ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:01:21.40234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:21.003533Z","time spent":"398.700804ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:01:21.402458Z","caller":"traceutil/trace.go:171","msg":"trace[1343647352] linearizableReadLoop","detail":"{readStateIndex:26104; appliedIndex:26102; }","duration":"400.034941ms","start":"2026-08-05T08:01:21.00238Z","end":"2026-08-05T08:01:21.402415Z","steps":["trace[1343647352] 'read index received' (duration: 114.783165ms)","trace[1343647352] 'applied index is now lower than readState.Index' (duration: 285.250446ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:21.402564Z","caller":"traceutil/trace.go:171","msg":"trace[2048366707] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"310.430301ms","start":"2026-08-05T08:01:21.09212Z","end":"2026-08-05T08:01:21.40255Z","steps":["trace[2048366707] 'process raft request' (duration: 309.944131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:21.402638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:21.092098Z","time spent":"310.507432ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:01:21.403007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.601273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:01:21.403287Z","caller":"traceutil/trace.go:171","msg":"trace[1311011855] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25291; }","duration":"400.912839ms","start":"2026-08-05T08:01:21.002364Z","end":"2026-08-05T08:01:21.403277Z","steps":["trace[1311011855] 'agreement among raft nodes before linearized reading' (duration: 400.247195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:21.403312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:21.002345Z","time spent":"400.960831ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T08:01:21.431874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.031626ms","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-08-05T08:01:21.43194Z","caller":"traceutil/trace.go:171","msg":"trace[308860105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25293; }","duration":"363.146518ms","start":"2026-08-05T08:01:21.068777Z","end":"2026-08-05T08:01:21.431924Z","steps":["trace[308860105] 'agreement among raft nodes before linearized reading' (duration: 362.891213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:21.431978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:21.068751Z","time spent":"363.21778ms","remote":"127.0.0.1:49928","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-08-05T08:01:32.821939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.050978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:01:32.822031Z","caller":"traceutil/trace.go:171","msg":"trace[172140881] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25431; }","duration":"251.171051ms","start":"2026-08-05T08:01:32.570832Z","end":"2026-08-05T08:01:32.822003Z","steps":["trace[172140881] 'range keys from in-memory index tree' (duration: 250.892106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:32.822353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.442433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dd34fe11-c481-4c47-9ffe-eb399b3491f7\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-05T08:01:32.822372Z","caller":"traceutil/trace.go:171","msg":"trace[1836962417] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dd34fe11-c481-4c47-9ffe-eb399b3491f7; range_end:; response_count:1; response_revision:25431; }","duration":"265.510414ms","start":"2026-08-05T08:01:32.556853Z","end":"2026-08-05T08:01:32.822364Z","steps":["trace[1836962417] 'range keys from in-memory index tree' (duration: 265.297989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:32.822508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.10655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:01:32.822621Z","caller":"traceutil/trace.go:171","msg":"trace[584142371] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25431; }","duration":"252.307494ms","start":"2026-08-05T08:01:32.570287Z","end":"2026-08-05T08:01:32.822594Z","steps":["trace[584142371] 'range keys from in-memory index tree' (duration: 251.865794ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:32.822663Z","caller":"traceutil/trace.go:171","msg":"trace[1220937769] linearizableReadLoop","detail":"{readStateIndex:26250; appliedIndex:26249; }","duration":"165.800457ms","start":"2026-08-05T08:01:32.656837Z","end":"2026-08-05T08:01:32.822637Z","steps":["trace[1220937769] 'read index received' (duration: 67.952288ms)","trace[1220937769] 'applied index is now lower than readState.Index' (duration: 97.845499ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:32.822801Z","caller":"traceutil/trace.go:171","msg":"trace[1981665935] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"240.167666ms","start":"2026-08-05T08:01:32.58262Z","end":"2026-08-05T08:01:32.822788Z","steps":["trace[1981665935] 'process raft request' (duration: 142.254846ms)","trace[1981665935] 'compare' (duration: 97.205016ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:32.822718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.14282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T08:01:32.823227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.388028ms","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-08-05T08:01:32.823227Z","caller":"traceutil/trace.go:171","msg":"trace[2132149945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25431; }","duration":"252.872145ms","start":"2026-08-05T08:01:32.57033Z","end":"2026-08-05T08:01:32.823202Z","steps":["trace[2132149945] 'range keys from in-memory index tree' (duration: 252.033999ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:32.823259Z","caller":"traceutil/trace.go:171","msg":"trace[1980800797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25432; }","duration":"166.48572ms","start":"2026-08-05T08:01:32.656761Z","end":"2026-08-05T08:01:32.823246Z","steps":["trace[1980800797] 'agreement among raft nodes before linearized reading' (duration: 166.156924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:33.127764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.736972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:01:33.127996Z","caller":"traceutil/trace.go:171","msg":"trace[97104062] linearizableReadLoop","detail":"{readStateIndex:26252; appliedIndex:26250; }","duration":"252.507468ms","start":"2026-08-05T08:01:32.875465Z","end":"2026-08-05T08:01:33.127973Z","steps":["trace[97104062] 'read index received' (duration: 69.544771ms)","trace[97104062] 'applied index is now lower than readState.Index' (duration: 182.961257ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:33.12814Z","caller":"traceutil/trace.go:171","msg":"trace[143597776] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"299.694221ms","start":"2026-08-05T08:01:32.828411Z","end":"2026-08-05T08:01:33.128105Z","steps":["trace[143597776] 'process raft request' (duration: 116.48895ms)","trace[143597776] 'compare' (duration: 182.585169ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:33.128278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.798333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-05T08:01:33.12834Z","caller":"traceutil/trace.go:171","msg":"trace[2013809990] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25434; }","duration":"252.915176ms","start":"2026-08-05T08:01:32.875406Z","end":"2026-08-05T08:01:33.128321Z","steps":["trace[2013809990] 'agreement among raft nodes before linearized reading' (duration: 252.63308ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:33.128506Z","caller":"traceutil/trace.go:171","msg":"trace[710808794] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"299.872666ms","start":"2026-08-05T08:01:32.828586Z","end":"2026-08-05T08:01:33.128459Z","steps":["trace[710808794] 'process raft request' (duration: 299.321525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:33.128753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:32.828539Z","time spent":"300.08057ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:01:33.253737Z","caller":"traceutil/trace.go:171","msg":"trace[167552890] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"119.879429ms","start":"2026-08-05T08:01:33.133808Z","end":"2026-08-05T08:01:33.253687Z","steps":["trace[167552890] 'process raft request' (duration: 119.469451ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:34.822393Z","caller":"traceutil/trace.go:171","msg":"trace[1300259775] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"104.222258ms","start":"2026-08-05T08:01:34.718128Z","end":"2026-08-05T08:01:34.822351Z","steps":["trace[1300259775] 'process raft request' (duration: 104.022044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:38.030318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.074467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:01:38.030427Z","caller":"traceutil/trace.go:171","msg":"trace[889954312] linearizableReadLoop","detail":"{readStateIndex:26319; appliedIndex:26318; }","duration":"214.847438ms","start":"2026-08-05T08:01:37.815564Z","end":"2026-08-05T08:01:38.030412Z","steps":["trace[889954312] 'read index received' (duration: 37.565637ms)","trace[889954312] 'applied index is now lower than readState.Index' (duration: 177.280411ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:38.030509Z","caller":"traceutil/trace.go:171","msg":"trace[1523805022] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"238.284277ms","start":"2026-08-05T08:01:37.792211Z","end":"2026-08-05T08:01:38.030495Z","steps":["trace[1523805022] 'process raft request' (duration: 60.947745ms)","trace[1523805022] 'compare' (duration: 176.943784ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:38.030834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.242727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-05T08:01:38.030981Z","caller":"traceutil/trace.go:171","msg":"trace[266918728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25499; }","duration":"215.43637ms","start":"2026-08-05T08:01:37.81552Z","end":"2026-08-05T08:01:38.030957Z","steps":["trace[266918728] 'agreement among raft nodes before linearized reading' (duration: 215.163595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:38.030829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.964956ms","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-08-05T08:01:38.031339Z","caller":"traceutil/trace.go:171","msg":"trace[600293909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25499; }","duration":"203.538426ms","start":"2026-08-05T08:01:37.82777Z","end":"2026-08-05T08:01:38.031309Z","steps":["trace[600293909] 'agreement among raft nodes before linearized reading' (duration: 202.832722ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:38.383182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.910777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:01:38.383341Z","caller":"traceutil/trace.go:171","msg":"trace[411020939] linearizableReadLoop","detail":"{readStateIndex:26321; appliedIndex:26320; }","duration":"137.246373ms","start":"2026-08-05T08:01:38.246058Z","end":"2026-08-05T08:01:38.383304Z","steps":["trace[411020939] 'read index received' (duration: 57.761µs)","trace[411020939] 'applied index is now lower than readState.Index' (duration: 137.173672ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:38.383597Z","caller":"traceutil/trace.go:171","msg":"trace[2064678567] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"346.739633ms","start":"2026-08-05T08:01:38.036841Z","end":"2026-08-05T08:01:38.383581Z","steps":["trace[2064678567] 'process raft request' (duration: 208.318056ms)","trace[2064678567] 'compare' (duration: 137.776354ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:38.383679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:38.036813Z","time spent":"346.825805ms","remote":"127.0.0.1:49928","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-08-05T08:01:38.38452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.417647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-08-05T08:01:38.38465Z","caller":"traceutil/trace.go:171","msg":"trace[1857216263] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25501; }","duration":"138.629822ms","start":"2026-08-05T08:01:38.24599Z","end":"2026-08-05T08:01:38.38462Z","steps":["trace[1857216263] 'agreement among raft nodes before linearized reading' (duration: 138.038369ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:38.63897Z","caller":"traceutil/trace.go:171","msg":"trace[1526243856] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"249.062797ms","start":"2026-08-05T08:01:38.389858Z","end":"2026-08-05T08:01:38.638921Z","steps":["trace[1526243856] 'process raft request' (duration: 222.507795ms)","trace[1526243856] 'compare' (duration: 26.017672ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:39.188382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.311762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-05T08:01:39.188525Z","caller":"traceutil/trace.go:171","msg":"trace[1948898760] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25503; }","duration":"224.521706ms","start":"2026-08-05T08:01:38.963973Z","end":"2026-08-05T08:01:39.188494Z","steps":["trace[1948898760] 'count revisions from in-memory index tree' (duration: 224.192719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:39.189087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.38829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:39.189385Z","caller":"traceutil/trace.go:171","msg":"trace[1839513776] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:25503; }","duration":"145.702947ms","start":"2026-08-05T08:01:39.043657Z","end":"2026-08-05T08:01:39.18936Z","steps":["trace[1839513776] 'count revisions from in-memory index tree' (duration: 144.89737ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:47.191238Z","caller":"traceutil/trace.go:171","msg":"trace[1826378174] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"291.171167ms","start":"2026-08-05T08:01:46.900028Z","end":"2026-08-05T08:01:47.191199Z","steps":["trace[1826378174] 'process raft request' (duration: 290.989194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:47.751488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.017282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-05T08:01:47.751522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.869076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-05T08:01:47.751595Z","caller":"traceutil/trace.go:171","msg":"trace[1743846515] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25565; }","duration":"423.165664ms","start":"2026-08-05T08:01:47.328406Z","end":"2026-08-05T08:01:47.751572Z","steps":["trace[1743846515] 'range keys from in-memory index tree' (duration: 422.775356ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:47.751624Z","caller":"traceutil/trace.go:171","msg":"trace[1909588301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25565; }","duration":"420.00994ms","start":"2026-08-05T08:01:47.33159Z","end":"2026-08-05T08:01:47.7516Z","steps":["trace[1909588301] 'range keys from in-memory index tree' (duration: 419.702673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:47.75165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.328396Z","time spent":"423.227955ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T08:01:47.751673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.331576Z","time spent":"420.086901ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T08:01:47.752021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.092883ms","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-08-05T08:01:47.752299Z","caller":"traceutil/trace.go:171","msg":"trace[1635534520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25565; }","duration":"450.360439ms","start":"2026-08-05T08:01:47.301831Z","end":"2026-08-05T08:01:47.752191Z","steps":["trace[1635534520] 'range keys from in-memory index tree' (duration: 449.564953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:47.752575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.301811Z","time spent":"450.739588ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-05T08:01:47.81934Z","caller":"traceutil/trace.go:171","msg":"trace[1939020951] linearizableReadLoop","detail":"{readStateIndex:26388; appliedIndex:26387; }","duration":"348.800025ms","start":"2026-08-05T08:01:47.470506Z","end":"2026-08-05T08:01:47.819306Z","steps":["trace[1939020951] 'read index received' (duration: 348.622441ms)","trace[1939020951] 'applied index is now lower than readState.Index' (duration: 176.124µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:47.819422Z","caller":"traceutil/trace.go:171","msg":"trace[640695863] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"377.46407ms","start":"2026-08-05T08:01:47.441913Z","end":"2026-08-05T08:01:47.819377Z","steps":["trace[640695863] 'process raft request' (duration: 377.162014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:47.819784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.246574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:01:47.819908Z","caller":"traceutil/trace.go:171","msg":"trace[1329424291] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25566; }","duration":"349.432728ms","start":"2026-08-05T08:01:47.470442Z","end":"2026-08-05T08:01:47.819875Z","steps":["trace[1329424291] 'agreement among raft nodes before linearized reading' (duration: 349.032559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:47.819974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.470409Z","time spent":"349.555101ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T08:01:47.820075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.441877Z","time spent":"377.796067ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:01:48.320544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500116171677922117,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T08:01:48.635242Z","caller":"traceutil/trace.go:171","msg":"trace[958514210] transaction","detail":"{read_only:false; response_revision:25567; number_of_response:1; }","duration":"1.130053424s","start":"2026-08-05T08:01:47.505145Z","end":"2026-08-05T08:01:48.635198Z","steps":["trace[958514210] 'process raft request' (duration: 1.129778519s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.635438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.505109Z","time spent":"1.130227838s","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:01:48.635711Z","caller":"traceutil/trace.go:171","msg":"trace[2113228286] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"1.122426649s","start":"2026-08-05T08:01:47.513225Z","end":"2026-08-05T08:01:48.635651Z","steps":["trace[2113228286] 'process raft request' (duration: 1.122221674s)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:48.635888Z","caller":"traceutil/trace.go:171","msg":"trace[262627905] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"1.112547976s","start":"2026-08-05T08:01:47.523322Z","end":"2026-08-05T08:01:48.63587Z","steps":["trace[262627905] 'process raft request' (duration: 1.112283101s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.635963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.51319Z","time spent":"1.122637252s","remote":"127.0.0.1:49928","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-08-05T08:01:48.635982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.52331Z","time spent":"1.112626407s","remote":"127.0.0.1:49928","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-08-05T08:01:48.635717Z","caller":"traceutil/trace.go:171","msg":"trace[1292270314] transaction","detail":"{read_only:false; response_revision:25568; number_of_response:1; }","duration":"1.129002222s","start":"2026-08-05T08:01:47.506673Z","end":"2026-08-05T08:01:48.635675Z","steps":["trace[1292270314] 'process raft request' (duration: 1.128469511s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.636321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.506659Z","time spent":"1.129462321s","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:01:48.636417Z","caller":"traceutil/trace.go:171","msg":"trace[787352740] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"876.568176ms","start":"2026-08-05T08:01:47.759834Z","end":"2026-08-05T08:01:48.636402Z","steps":["trace[787352740] 'process raft request' (duration: 876.455373ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:48.636374Z","caller":"traceutil/trace.go:171","msg":"trace[674634786] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"878.665659ms","start":"2026-08-05T08:01:47.757684Z","end":"2026-08-05T08:01:48.636349Z","steps":["trace[674634786] 'process raft request' (duration: 878.490735ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:48.636403Z","caller":"traceutil/trace.go:171","msg":"trace[1464697491] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"879.315722ms","start":"2026-08-05T08:01:47.757025Z","end":"2026-08-05T08:01:48.63634Z","steps":["trace[1464697491] 'process raft request' (duration: 878.745891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.636572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.759815Z","time spent":"876.651117ms","remote":"127.0.0.1:49928","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-08-05T08:01:48.636664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.757675Z","time spent":"878.848322ms","remote":"127.0.0.1:49928","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-08-05T08:01:48.636783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.756983Z","time spent":"879.603387ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:01:48.658021Z","caller":"traceutil/trace.go:171","msg":"trace[727613671] transaction","detail":"{read_only:false; response_revision:25574; number_of_response:1; }","duration":"589.507772ms","start":"2026-08-05T08:01:48.068486Z","end":"2026-08-05T08:01:48.657993Z","steps":["trace[727613671] 'process raft request' (duration: 589.333489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.658168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.06846Z","time spent":"589.632625ms","remote":"127.0.0.1:49928","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-08-05T08:01:48.6704Z","caller":"traceutil/trace.go:171","msg":"trace[172578926] linearizableReadLoop","detail":"{readStateIndex:26396; appliedIndex:26388; }","duration":"850.798699ms","start":"2026-08-05T08:01:47.81958Z","end":"2026-08-05T08:01:48.670378Z","steps":["trace[172578926] 'read index received' (duration: 815.397906ms)","trace[172578926] 'applied index is now lower than readState.Index' (duration: 35.398973ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:48.670643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099969999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:48.670741Z","caller":"traceutil/trace.go:171","msg":"trace[1811654118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25574; }","duration":"1.100120332s","start":"2026-08-05T08:01:47.570607Z","end":"2026-08-05T08:01:48.670727Z","steps":["trace[1811654118] 'agreement among raft nodes before linearized reading' (duration: 1.099990689s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.670842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.570582Z","time spent":"1.100243814s","remote":"127.0.0.1:49676","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-08-05T08:01:48.670937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.306576ms","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-08-05T08:01:48.671002Z","caller":"traceutil/trace.go:171","msg":"trace[485340469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25574; }","duration":"234.429338ms","start":"2026-08-05T08:01:48.436561Z","end":"2026-08-05T08:01:48.670991Z","steps":["trace[485340469] 'agreement among raft nodes before linearized reading' (duration: 234.231124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.671053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.790055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-05T08:01:48.670859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.721173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-05T08:01:48.6712Z","caller":"traceutil/trace.go:171","msg":"trace[2018255372] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25574; }","duration":"584.027951ms","start":"2026-08-05T08:01:48.087142Z","end":"2026-08-05T08:01:48.67117Z","steps":["trace[2018255372] 'agreement among raft nodes before linearized reading' (duration: 583.708143ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:48.671207Z","caller":"traceutil/trace.go:171","msg":"trace[365133946] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25574; }","duration":"537.102012ms","start":"2026-08-05T08:01:48.134075Z","end":"2026-08-05T08:01:48.671177Z","steps":["trace[365133946] 'agreement among raft nodes before linearized reading' (duration: 536.710093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.671258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.08712Z","time spent":"584.125722ms","remote":"127.0.0.1:50172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":40,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:01:48.671101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.105479ms","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-08-05T08:01:48.671467Z","caller":"traceutil/trace.go:171","msg":"trace[287551918] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25574; }","duration":"509.489008ms","start":"2026-08-05T08:01:48.1619Z","end":"2026-08-05T08:01:48.671389Z","steps":["trace[287551918] 'agreement among raft nodes before linearized reading' (duration: 508.980587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.670849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039634737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T08:01:48.67152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.161875Z","time spent":"509.635001ms","remote":"127.0.0.1:49928","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-08-05T08:01:48.671541Z","caller":"traceutil/trace.go:171","msg":"trace[1257930633] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25574; }","duration":"1.040420624s","start":"2026-08-05T08:01:47.631093Z","end":"2026-08-05T08:01:48.671514Z","steps":["trace[1257930633] 'agreement among raft nodes before linearized reading' (duration: 1.039603666s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.671718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.631067Z","time spent":"1.040624817s","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:01:48.670848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.535893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T08:01:48.671289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.13405Z","time spent":"537.219454ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-08-05T08:01:48.671895Z","caller":"traceutil/trace.go:171","msg":"trace[301992713] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25574; }","duration":"896.698627ms","start":"2026-08-05T08:01:47.775148Z","end":"2026-08-05T08:01:48.671847Z","steps":["trace[301992713] 'agreement among raft nodes before linearized reading' (duration: 895.540243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.671967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.77513Z","time spent":"896.816909ms","remote":"127.0.0.1:45994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":29,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:01:48.671079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.400059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-05T08:01:48.671215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.48964ms","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-08-05T08:01:48.672231Z","caller":"traceutil/trace.go:171","msg":"trace[1591735235] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25574; }","duration":"328.850639ms","start":"2026-08-05T08:01:48.343361Z","end":"2026-08-05T08:01:48.672211Z","steps":["trace[1591735235] 'agreement among raft nodes before linearized reading' (duration: 327.361368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.670742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044401614s","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-08-05T08:01:48.672376Z","caller":"traceutil/trace.go:171","msg":"trace[795230225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25574; }","duration":"1.046088609s","start":"2026-08-05T08:01:47.626276Z","end":"2026-08-05T08:01:48.672365Z","steps":["trace[795230225] 'agreement among raft nodes before linearized reading' (duration: 1.044310642s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.672484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:47.626252Z","time spent":"1.046214022s","remote":"127.0.0.1:49928","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":"info","ts":"2026-08-05T08:01:48.672055Z","caller":"traceutil/trace.go:171","msg":"trace[288036445] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:25574; }","duration":"659.523903ms","start":"2026-08-05T08:01:48.012507Z","end":"2026-08-05T08:01:48.672031Z","steps":["trace[288036445] 'agreement among raft nodes before linearized reading' (duration: 658.150734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:48.672699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.012478Z","time spent":"660.198796ms","remote":"127.0.0.1:45844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":11,"response size":31,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:01:48.67265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.343336Z","time spent":"329.022022ms","remote":"127.0.0.1:41154","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-08-05T08:01:49.14576Z","caller":"traceutil/trace.go:171","msg":"trace[939806563] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"469.263536ms","start":"2026-08-05T08:01:48.676456Z","end":"2026-08-05T08:01:49.14572Z","steps":["trace[939806563] 'process raft request' (duration: 468.97699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.145993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.676426Z","time spent":"469.43551ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:01:49.146102Z","caller":"traceutil/trace.go:171","msg":"trace[1591434466] linearizableReadLoop","detail":"{readStateIndex:26401; appliedIndex:26397; }","duration":"467.331957ms","start":"2026-08-05T08:01:48.678736Z","end":"2026-08-05T08:01:49.146068Z","steps":["trace[1591434466] 'read index received' (duration: 66.889017ms)","trace[1591434466] 'applied index is now lower than readState.Index' (duration: 400.44147ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:49.146118Z","caller":"traceutil/trace.go:171","msg":"trace[1422315277] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"468.300136ms","start":"2026-08-05T08:01:48.67777Z","end":"2026-08-05T08:01:49.14607Z","steps":["trace[1422315277] 'process raft request' (duration: 467.887558ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:49.146275Z","caller":"traceutil/trace.go:171","msg":"trace[1955353649] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"468.436919ms","start":"2026-08-05T08:01:48.677822Z","end":"2026-08-05T08:01:49.146259Z","steps":["trace[1955353649] 'process raft request' (duration: 468.094662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.146312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.67776Z","time spent":"468.447659ms","remote":"127.0.0.1:49928","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-08-05T08:01:49.14643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.677811Z","time spent":"468.50321ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:01:49.146629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.50522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-05T08:01:49.146604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.02808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:49.14668Z","caller":"traceutil/trace.go:171","msg":"trace[447072735] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25578; }","duration":"280.591562ms","start":"2026-08-05T08:01:48.866075Z","end":"2026-08-05T08:01:49.146666Z","steps":["trace[447072735] 'agreement among raft nodes before linearized reading' (duration: 280.420039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.146701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.769147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:49.146717Z","caller":"traceutil/trace.go:171","msg":"trace[1980764226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25578; }","duration":"465.185552ms","start":"2026-08-05T08:01:48.6815Z","end":"2026-08-05T08:01:49.146685Z","steps":["trace[1980764226] 'agreement among raft nodes before linearized reading' (duration: 464.957378ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:49.146758Z","caller":"traceutil/trace.go:171","msg":"trace[661855594] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25578; }","duration":"447.890189ms","start":"2026-08-05T08:01:48.698849Z","end":"2026-08-05T08:01:49.146739Z","steps":["trace[661855594] 'agreement among raft nodes before linearized reading' (duration: 447.717026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.146757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.570628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-08-05T08:01:49.146775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.681481Z","time spent":"465.283365ms","remote":"127.0.0.1:49676","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-08-05T08:01:49.146778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.862397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:958"} {"level":"warn","ts":"2026-08-05T08:01:49.146793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.698834Z","time spent":"447.94896ms","remote":"127.0.0.1:45450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-08-05T08:01:49.146805Z","caller":"traceutil/trace.go:171","msg":"trace[361098840] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25578; }","duration":"460.626639ms","start":"2026-08-05T08:01:48.68616Z","end":"2026-08-05T08:01:49.146786Z","steps":["trace[361098840] 'agreement among raft nodes before linearized reading' (duration: 460.449666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.146769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.00223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"warn","ts":"2026-08-05T08:01:49.14684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.68615Z","time spent":"460.68291ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-05T08:01:49.146835Z","caller":"traceutil/trace.go:171","msg":"trace[510856728] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25578; }","duration":"419.948049ms","start":"2026-08-05T08:01:48.726867Z","end":"2026-08-05T08:01:49.146815Z","steps":["trace[510856728] 'agreement among raft nodes before linearized reading' (duration: 419.789746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.146881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.726855Z","time spent":"420.01884ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":981,"request content":"key:\"/registry/services/endpoints/openstack/rabbitmq-manila-nodes\" "} {"level":"info","ts":"2026-08-05T08:01:49.146873Z","caller":"traceutil/trace.go:171","msg":"trace[1376493299] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25578; }","duration":"468.146553ms","start":"2026-08-05T08:01:48.678699Z","end":"2026-08-05T08:01:49.146845Z","steps":["trace[1376493299] 'agreement among raft nodes before linearized reading' (duration: 467.828546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.146929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:48.678678Z","time spent":"468.239565ms","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1178,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-08-05T08:01:49.147127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.906466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:01:49.147211Z","caller":"traceutil/trace.go:171","msg":"trace[1485018907] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25578; }","duration":"278.025339ms","start":"2026-08-05T08:01:48.869166Z","end":"2026-08-05T08:01:49.147192Z","steps":["trace[1485018907] 'agreement among raft nodes before linearized reading' (duration: 277.430858ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:49.387684Z","caller":"traceutil/trace.go:171","msg":"trace[39117166] linearizableReadLoop","detail":"{readStateIndex:26402; appliedIndex:26401; }","duration":"235.086662ms","start":"2026-08-05T08:01:49.152562Z","end":"2026-08-05T08:01:49.387649Z","steps":["trace[39117166] 'read index received' (duration: 176.484µs)","trace[39117166] 'applied index is now lower than readState.Index' (duration: 234.908758ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:01:49.387785Z","caller":"traceutil/trace.go:171","msg":"trace[391615134] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"235.200334ms","start":"2026-08-05T08:01:49.152537Z","end":"2026-08-05T08:01:49.387737Z","steps":["trace[391615134] 'process raft request' (duration: 233.928529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.387951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.372378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-08-05T08:01:49.388058Z","caller":"traceutil/trace.go:171","msg":"trace[1328896918] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25579; }","duration":"235.542901ms","start":"2026-08-05T08:01:49.152489Z","end":"2026-08-05T08:01:49.388032Z","steps":["trace[1328896918] 'agreement among raft nodes before linearized reading' (duration: 235.288866ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:49.388874Z","caller":"traceutil/trace.go:171","msg":"trace[1882984766] transaction","detail":"{read_only:false; response_revision:25580; number_of_response:1; }","duration":"191.583954ms","start":"2026-08-05T08:01:49.197248Z","end":"2026-08-05T08:01:49.388832Z","steps":["trace[1882984766] 'process raft request' (duration: 191.288098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.388978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.056243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-08-05T08:01:49.389089Z","caller":"traceutil/trace.go:171","msg":"trace[2037839912] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25580; }","duration":"236.209126ms","start":"2026-08-05T08:01:49.152851Z","end":"2026-08-05T08:01:49.38906Z","steps":["trace[2037839912] 'agreement among raft nodes before linearized reading' (duration: 235.91724ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:49.852412Z","caller":"traceutil/trace.go:171","msg":"trace[735037487] linearizableReadLoop","detail":"{readStateIndex:26405; appliedIndex:26404; }","duration":"424.231676ms","start":"2026-08-05T08:01:49.428142Z","end":"2026-08-05T08:01:49.852374Z","steps":["trace[735037487] 'read index received' (duration: 424.030031ms)","trace[735037487] 'applied index is now lower than readState.Index' (duration: 200.425µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:01:49.852692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.532311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9600"} {"level":"info","ts":"2026-08-05T08:01:49.852737Z","caller":"traceutil/trace.go:171","msg":"trace[99673424] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25582; }","duration":"424.621304ms","start":"2026-08-05T08:01:49.428105Z","end":"2026-08-05T08:01:49.852726Z","steps":["trace[99673424] 'agreement among raft nodes before linearized reading' (duration: 424.400879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.852792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:49.428089Z","time spent":"424.691685ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9623,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" "} {"level":"info","ts":"2026-08-05T08:01:49.852599Z","caller":"traceutil/trace.go:171","msg":"trace[1245285764] transaction","detail":"{read_only:false; response_revision:25582; number_of_response:1; }","duration":"436.364534ms","start":"2026-08-05T08:01:49.416067Z","end":"2026-08-05T08:01:49.852431Z","steps":["trace[1245285764] 'process raft request' (duration: 436.072208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:01:49.853112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:01:49.416042Z","time spent":"436.962806ms","remote":"127.0.0.1:49760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:01:49.853515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.989394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-08-05T08:01:49.853521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.053663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:01:49.853605Z","caller":"traceutil/trace.go:171","msg":"trace[880124518] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25583; }","duration":"147.147067ms","start":"2026-08-05T08:01:49.706434Z","end":"2026-08-05T08:01:49.853581Z","steps":["trace[880124518] 'agreement among raft nodes before linearized reading' (duration: 146.87724ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:01:49.853628Z","caller":"traceutil/trace.go:171","msg":"trace[1560265583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25583; }","duration":"143.197106ms","start":"2026-08-05T08:01:49.710406Z","end":"2026-08-05T08:01:49.853603Z","steps":["trace[1560265583] 'agreement among raft nodes before linearized reading' (duration: 143.001812ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:16.888585Z","caller":"traceutil/trace.go:171","msg":"trace[1217664658] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"126.31232ms","start":"2026-08-05T08:02:16.762234Z","end":"2026-08-05T08:02:16.888546Z","steps":["trace[1217664658] 'process raft request' (duration: 126.008744ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:17.200962Z","caller":"traceutil/trace.go:171","msg":"trace[678311731] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"428.539244ms","start":"2026-08-05T08:02:16.772395Z","end":"2026-08-05T08:02:17.200934Z","steps":["trace[678311731] 'process raft request' (duration: 428.468743ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:17.201001Z","caller":"traceutil/trace.go:171","msg":"trace[621313414] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"435.329593ms","start":"2026-08-05T08:02:16.765616Z","end":"2026-08-05T08:02:17.200946Z","steps":["trace[621313414] 'process raft request' (duration: 435.039887ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:17.201097Z","caller":"traceutil/trace.go:171","msg":"trace[862740977] linearizableReadLoop","detail":"{readStateIndex:26621; appliedIndex:26617; }","duration":"427.33239ms","start":"2026-08-05T08:02:16.773745Z","end":"2026-08-05T08:02:17.201077Z","steps":["trace[862740977] 'read index received' (duration: 114.671233ms)","trace[862740977] 'applied index is now lower than readState.Index' (duration: 312.659797ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:17.201061Z","caller":"traceutil/trace.go:171","msg":"trace[869608100] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"432.060216ms","start":"2026-08-05T08:02:16.768921Z","end":"2026-08-05T08:02:17.200981Z","steps":["trace[869608100] 'process raft request' (duration: 431.906593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.201233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:16.765582Z","time spent":"435.518736ms","remote":"127.0.0.1:49928","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-08-05T08:02:17.201304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:16.768909Z","time spent":"432.26504ms","remote":"127.0.0.1:49928","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-08-05T08:02:17.201328Z","caller":"traceutil/trace.go:171","msg":"trace[1575837004] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"427.576675ms","start":"2026-08-05T08:02:16.773738Z","end":"2026-08-05T08:02:17.201315Z","steps":["trace[1575837004] 'process raft request' (duration: 427.163386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.201449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:16.773726Z","time spent":"427.690227ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:02:17.201464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.722057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-05T08:02:17.201503Z","caller":"traceutil/trace.go:171","msg":"trace[19406048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25794; }","duration":"427.804359ms","start":"2026-08-05T08:02:16.773689Z","end":"2026-08-05T08:02:17.201494Z","steps":["trace[19406048] 'agreement among raft nodes before linearized reading' (duration: 427.660996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.201541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:16.773671Z","time spent":"427.86232ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-05T08:02:17.201671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.973371ms","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-08-05T08:02:17.201747Z","caller":"traceutil/trace.go:171","msg":"trace[1284560260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25794; }","duration":"427.094924ms","start":"2026-08-05T08:02:16.774635Z","end":"2026-08-05T08:02:17.20173Z","steps":["trace[1284560260] 'agreement among raft nodes before linearized reading' (duration: 426.867639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.201786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:16.774623Z","time spent":"427.156085ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T08:02:17.20185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.282567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-05T08:02:17.201886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.788241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-05T08:02:17.201908Z","caller":"traceutil/trace.go:171","msg":"trace[20986515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25794; }","duration":"308.387589ms","start":"2026-08-05T08:02:16.893507Z","end":"2026-08-05T08:02:17.201894Z","steps":["trace[20986515] 'agreement among raft nodes before linearized reading' (duration: 308.192535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.201971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:16.893489Z","time spent":"308.472051ms","remote":"127.0.0.1:49928","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-08-05T08:02:17.201925Z","caller":"traceutil/trace.go:171","msg":"trace[1794590918] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25794; }","duration":"409.838282ms","start":"2026-08-05T08:02:16.792074Z","end":"2026-08-05T08:02:17.201912Z","steps":["trace[1794590918] 'agreement among raft nodes before linearized reading' (duration: 409.667058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.202113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:16.792065Z","time spent":"410.033165ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T08:02:17.201169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:16.772386Z","time spent":"428.644266ms","remote":"127.0.0.1:49928","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-08-05T08:02:17.461139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.908169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:02:17.461264Z","caller":"traceutil/trace.go:171","msg":"trace[1645860170] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"254.710973ms","start":"2026-08-05T08:02:17.206528Z","end":"2026-08-05T08:02:17.461239Z","steps":["trace[1645860170] 'process raft request' (duration: 134.60957ms)","trace[1645860170] 'compare' (duration: 119.769437ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:17.781256Z","caller":"traceutil/trace.go:171","msg":"trace[2041039518] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"574.095537ms","start":"2026-08-05T08:02:17.207099Z","end":"2026-08-05T08:02:17.781195Z","steps":["trace[2041039518] 'process raft request' (duration: 560.711863ms)","trace[2041039518] 'compare' (duration: 13.082398ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:17.781485Z","caller":"traceutil/trace.go:171","msg":"trace[246467593] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"573.995595ms","start":"2026-08-05T08:02:17.207472Z","end":"2026-08-05T08:02:17.781468Z","steps":["trace[246467593] 'process raft request' (duration: 573.71801ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:17.781463Z","caller":"traceutil/trace.go:171","msg":"trace[962126277] linearizableReadLoop","detail":"{readStateIndex:26626; appliedIndex:26622; }","duration":"550.020515ms","start":"2026-08-05T08:02:17.231409Z","end":"2026-08-05T08:02:17.781429Z","steps":["trace[962126277] 'read index received' (duration: 109.809153ms)","trace[962126277] 'applied index is now lower than readState.Index' (duration: 440.203101ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:17.781644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.207459Z","time spent":"574.084527ms","remote":"127.0.0.1:49928","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-08-05T08:02:17.781655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.244099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:02:17.781694Z","caller":"traceutil/trace.go:171","msg":"trace[666878571] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25798; }","duration":"550.306451ms","start":"2026-08-05T08:02:17.231374Z","end":"2026-08-05T08:02:17.78168Z","steps":["trace[666878571] 'agreement among raft nodes before linearized reading' (duration: 550.146607ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:17.781646Z","caller":"traceutil/trace.go:171","msg":"trace[1608932032] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"574.336842ms","start":"2026-08-05T08:02:17.20729Z","end":"2026-08-05T08:02:17.781627Z","steps":["trace[1608932032] 'process raft request' (duration: 573.788051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.781718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.231354Z","time spent":"550.355951ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T08:02:17.781777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.207278Z","time spent":"574.453945ms","remote":"127.0.0.1:49928","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-08-05T08:02:17.781434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.207075Z","time spent":"574.268861ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:02:17.793769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.700731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:02:17.793829Z","caller":"traceutil/trace.go:171","msg":"trace[401048516] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25799; }","duration":"556.806593ms","start":"2026-08-05T08:02:17.237009Z","end":"2026-08-05T08:02:17.793816Z","steps":["trace[401048516] 'agreement among raft nodes before linearized reading' (duration: 556.65602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.793825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.436793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T08:02:17.793917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.136737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:17.793963Z","caller":"traceutil/trace.go:171","msg":"trace[1582258576] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25799; }","duration":"314.220328ms","start":"2026-08-05T08:02:17.479728Z","end":"2026-08-05T08:02:17.793949Z","steps":["trace[1582258576] 'agreement among raft nodes before linearized reading' (duration: 314.156407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.793999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.479703Z","time spent":"314.28737ms","remote":"127.0.0.1:40506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-08-05T08:02:17.794056Z","caller":"traceutil/trace.go:171","msg":"trace[506439740] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"238.488712ms","start":"2026-08-05T08:02:17.555554Z","end":"2026-08-05T08:02:17.794043Z","steps":["trace[506439740] 'process raft request' (duration: 238.017152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.79422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.305009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:17.794263Z","caller":"traceutil/trace.go:171","msg":"trace[2040505106] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25799; }","duration":"405.398051ms","start":"2026-08-05T08:02:17.388853Z","end":"2026-08-05T08:02:17.794251Z","steps":["trace[2040505106] 'agreement among raft nodes before linearized reading' (duration: 405.33637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.794306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.388828Z","time spent":"405.465793ms","remote":"127.0.0.1:40382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:02:17.794443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.963665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-05T08:02:17.794478Z","caller":"traceutil/trace.go:171","msg":"trace[261811241] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25799; }","duration":"222.014446ms","start":"2026-08-05T08:02:17.57245Z","end":"2026-08-05T08:02:17.794465Z","steps":["trace[261811241] 'agreement among raft nodes before linearized reading' (duration: 221.895254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.794522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.436121ms","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-08-05T08:02:17.79456Z","caller":"traceutil/trace.go:171","msg":"trace[1796801758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25799; }","duration":"473.485002ms","start":"2026-08-05T08:02:17.321066Z","end":"2026-08-05T08:02:17.794551Z","steps":["trace[1796801758] 'agreement among raft nodes before linearized reading' (duration: 473.38519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.794594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.321059Z","time spent":"473.528443ms","remote":"127.0.0.1:49928","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-08-05T08:02:17.79474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.213278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-05T08:02:17.794772Z","caller":"traceutil/trace.go:171","msg":"trace[407426460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25799; }","duration":"474.300049ms","start":"2026-08-05T08:02:17.320464Z","end":"2026-08-05T08:02:17.794764Z","steps":["trace[407426460] 'agreement among raft nodes before linearized reading' (duration: 474.215027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.794805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.320439Z","time spent":"474.360601ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-05T08:02:17.793918Z","caller":"traceutil/trace.go:171","msg":"trace[2142259531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25799; }","duration":"222.601467ms","start":"2026-08-05T08:02:17.571292Z","end":"2026-08-05T08:02:17.793894Z","steps":["trace[2142259531] 'agreement among raft nodes before linearized reading' (duration: 222.434463ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:17.793865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.236993Z","time spent":"556.864764ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T08:02:18.113023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.171919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:02:18.113174Z","caller":"traceutil/trace.go:171","msg":"trace[1786877539] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"313.573145ms","start":"2026-08-05T08:02:17.799577Z","end":"2026-08-05T08:02:18.113151Z","steps":["trace[1786877539] 'process raft request' (duration: 133.173841ms)","trace[1786877539] 'compare' (duration: 179.67217ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:18.113239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.799556Z","time spent":"313.652046ms","remote":"127.0.0.1:49928","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-08-05T08:02:18.293593Z","caller":"traceutil/trace.go:171","msg":"trace[648657635] linearizableReadLoop","detail":"{readStateIndex:26631; appliedIndex:26627; }","duration":"431.196208ms","start":"2026-08-05T08:02:17.862369Z","end":"2026-08-05T08:02:18.293566Z","steps":["trace[648657635] 'read index received' (duration: 70.398498ms)","trace[648657635] 'applied index is now lower than readState.Index' (duration: 360.79694ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:18.293809Z","caller":"traceutil/trace.go:171","msg":"trace[1468857188] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"493.034341ms","start":"2026-08-05T08:02:17.800762Z","end":"2026-08-05T08:02:18.293796Z","steps":["trace[1468857188] 'process raft request' (duration: 435.521615ms)","trace[1468857188] 'compare' (duration: 56.966765ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:18.293915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.800733Z","time spent":"493.101013ms","remote":"127.0.0.1:49928","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-08-05T08:02:18.294058Z","caller":"traceutil/trace.go:171","msg":"trace[896040037] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"487.332095ms","start":"2026-08-05T08:02:17.806721Z","end":"2026-08-05T08:02:18.294053Z","steps":["trace[896040037] 'process raft request' (duration: 486.777714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:18.294098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.806711Z","time spent":"487.370386ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:02:18.294164Z","caller":"traceutil/trace.go:171","msg":"trace[741834659] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"433.173629ms","start":"2026-08-05T08:02:17.860986Z","end":"2026-08-05T08:02:18.294159Z","steps":["trace[741834659] 'process raft request' (duration: 432.548497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:18.294194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.860956Z","time spent":"433.218359ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:02:18.294281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.920302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:18.294307Z","caller":"traceutil/trace.go:171","msg":"trace[887878663] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25803; }","duration":"431.989584ms","start":"2026-08-05T08:02:17.862311Z","end":"2026-08-05T08:02:18.2943Z","steps":["trace[887878663] 'agreement among raft nodes before linearized reading' (duration: 431.946263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:18.294327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.862289Z","time spent":"432.033914ms","remote":"127.0.0.1:40290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:02:18.294492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.310265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:02:18.294514Z","caller":"traceutil/trace.go:171","msg":"trace[1877437058] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25803; }","duration":"372.332115ms","start":"2026-08-05T08:02:17.922177Z","end":"2026-08-05T08:02:18.294509Z","steps":["trace[1877437058] 'agreement among raft nodes before linearized reading' (duration: 372.233953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:18.294526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.922063Z","time spent":"372.460178ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T08:02:18.294844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.707477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-08-05T08:02:18.294867Z","caller":"traceutil/trace.go:171","msg":"trace[822487525] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25803; }","duration":"193.787608ms","start":"2026-08-05T08:02:18.101074Z","end":"2026-08-05T08:02:18.294862Z","steps":["trace[822487525] 'agreement among raft nodes before linearized reading' (duration: 193.728837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:18.294974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.537773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:18.294995Z","caller":"traceutil/trace.go:171","msg":"trace[1884692267] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25803; }","duration":"312.601245ms","start":"2026-08-05T08:02:17.982387Z","end":"2026-08-05T08:02:18.294989Z","steps":["trace[1884692267] 'agreement among raft nodes before linearized reading' (duration: 312.569544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:18.29501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:17.982365Z","time spent":"312.641696ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true "} {"level":"warn","ts":"2026-08-05T08:02:19.030424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.898414ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-05T08:02:19.030541Z","caller":"traceutil/trace.go:171","msg":"trace[1726718442] linearizableReadLoop","detail":"{readStateIndex:26632; appliedIndex:26631; }","duration":"338.824201ms","start":"2026-08-05T08:02:18.691694Z","end":"2026-08-05T08:02:19.030518Z","steps":["trace[1726718442] 'read index received' (duration: 184.797755ms)","trace[1726718442] 'applied index is now lower than readState.Index' (duration: 154.024816ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:19.030639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.933334ms","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-08-05T08:02:19.030662Z","caller":"traceutil/trace.go:171","msg":"trace[2035213949] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25803; }","duration":"339.015995ms","start":"2026-08-05T08:02:18.691636Z","end":"2026-08-05T08:02:19.030652Z","steps":["trace[2035213949] 'agreement among raft nodes before linearized reading' (duration: 338.933003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:19.030697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:18.691613Z","time spent":"339.076076ms","remote":"127.0.0.1:46054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-05T08:02:19.094909Z","caller":"traceutil/trace.go:171","msg":"trace[1403953248] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"199.524366ms","start":"2026-08-05T08:02:18.895351Z","end":"2026-08-05T08:02:19.094875Z","steps":["trace[1403953248] 'process raft request' (duration: 199.275991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:19.30823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.39131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-05T08:02:19.308345Z","caller":"traceutil/trace.go:171","msg":"trace[1771552541] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25804; }","duration":"145.553924ms","start":"2026-08-05T08:02:19.162766Z","end":"2026-08-05T08:02:19.30832Z","steps":["trace[1771552541] 'range keys from in-memory index tree' (duration: 145.246787ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:19.308464Z","caller":"traceutil/trace.go:171","msg":"trace[1119267008] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"101.260938ms","start":"2026-08-05T08:02:19.207134Z","end":"2026-08-05T08:02:19.308395Z","steps":["trace[1119267008] 'process raft request' (duration: 99.956022ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:19.603813Z","caller":"traceutil/trace.go:171","msg":"trace[1782498577] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"395.560049ms","start":"2026-08-05T08:02:19.208215Z","end":"2026-08-05T08:02:19.603775Z","steps":["trace[1782498577] 'process raft request' (duration: 319.6843ms)","trace[1782498577] 'compare' (duration: 75.661095ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:19.60406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:19.208184Z","time spent":"395.738614ms","remote":"127.0.0.1:49928","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-08-05T08:02:19.604094Z","caller":"traceutil/trace.go:171","msg":"trace[1321560609] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"395.798255ms","start":"2026-08-05T08:02:19.208278Z","end":"2026-08-05T08:02:19.604076Z","steps":["trace[1321560609] 'process raft request' (duration: 395.456978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:19.604155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:19.208229Z","time spent":"395.896627ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:02:19.604248Z","caller":"traceutil/trace.go:171","msg":"trace[315060350] transaction","detail":"{read_only:false; response_revision:25808; number_of_response:1; }","duration":"395.604542ms","start":"2026-08-05T08:02:19.208581Z","end":"2026-08-05T08:02:19.604186Z","steps":["trace[315060350] 'process raft request' (duration: 395.368177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:19.604523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:19.208573Z","time spent":"395.786045ms","remote":"127.0.0.1:49928","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-08-05T08:02:19.879015Z","caller":"traceutil/trace.go:171","msg":"trace[2130479947] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"565.40334ms","start":"2026-08-05T08:02:19.313581Z","end":"2026-08-05T08:02:19.878984Z","steps":["trace[2130479947] 'process raft request' (duration: 504.23159ms)","trace[2130479947] 'compare' (duration: 60.860084ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:19.879185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:19.313543Z","time spent":"565.541653ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T08:02:19.879031Z","caller":"traceutil/trace.go:171","msg":"trace[2041375836] linearizableReadLoop","detail":"{readStateIndex:26639; appliedIndex:26634; }","duration":"406.024374ms","start":"2026-08-05T08:02:19.472969Z","end":"2026-08-05T08:02:19.878993Z","steps":["trace[2041375836] 'read index received' (duration: 55.013133ms)","trace[2041375836] 'applied index is now lower than readState.Index' (duration: 351.009771ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:19.879442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.461412ms","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-08-05T08:02:19.879541Z","caller":"traceutil/trace.go:171","msg":"trace[952781692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25810; }","duration":"406.628966ms","start":"2026-08-05T08:02:19.472894Z","end":"2026-08-05T08:02:19.879523Z","steps":["trace[952781692] 'agreement among raft nodes before linearized reading' (duration: 406.315109ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:19.879621Z","caller":"traceutil/trace.go:171","msg":"trace[97066100] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"530.539307ms","start":"2026-08-05T08:02:19.349071Z","end":"2026-08-05T08:02:19.87961Z","steps":["trace[97066100] 'process raft request' (duration: 529.781682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:19.879618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:19.472867Z","time spent":"406.735798ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T08:02:19.879692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:19.349039Z","time spent":"530.609718ms","remote":"127.0.0.1:41030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7681,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T08:02:19.879916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.845149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:19.879952Z","caller":"traceutil/trace.go:171","msg":"trace[754858721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25811; }","duration":"309.936721ms","start":"2026-08-05T08:02:19.570007Z","end":"2026-08-05T08:02:19.879944Z","steps":["trace[754858721] 'agreement among raft nodes before linearized reading' (duration: 309.836489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:19.879974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:19.56997Z","time spent":"309.999423ms","remote":"127.0.0.1:49676","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-08-05T08:02:19.88071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.777297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:19.880819Z","caller":"traceutil/trace.go:171","msg":"trace[287992436] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:25811; }","duration":"309.91128ms","start":"2026-08-05T08:02:19.570874Z","end":"2026-08-05T08:02:19.880785Z","steps":["trace[287992436] 'agreement among raft nodes before linearized reading' (duration: 309.706196ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:19.880876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:19.570841Z","time spent":"310.019803ms","remote":"127.0.0.1:45430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true "} {"level":"info","ts":"2026-08-05T08:02:20.04118Z","caller":"traceutil/trace.go:171","msg":"trace[947581910] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"156.268372ms","start":"2026-08-05T08:02:19.884863Z","end":"2026-08-05T08:02:20.041131Z","steps":["trace[947581910] 'process raft request' (duration: 111.810184ms)","trace[947581910] 'compare' (duration: 44.313575ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:20.121245Z","caller":"traceutil/trace.go:171","msg":"trace[680512550] linearizableReadLoop","detail":"{readStateIndex:26646; appliedIndex:26640; }","duration":"231.164972ms","start":"2026-08-05T08:02:19.89005Z","end":"2026-08-05T08:02:20.121215Z","steps":["trace[680512550] 'read index received' (duration: 106.711429ms)","trace[680512550] 'applied index is now lower than readState.Index' (duration: 124.452172ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:20.121643Z","caller":"traceutil/trace.go:171","msg":"trace[298549032] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"234.270885ms","start":"2026-08-05T08:02:19.887363Z","end":"2026-08-05T08:02:20.121634Z","steps":["trace[298549032] 'process raft request' (duration: 233.572521ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:20.121788Z","caller":"traceutil/trace.go:171","msg":"trace[509416580] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"232.861397ms","start":"2026-08-05T08:02:19.888923Z","end":"2026-08-05T08:02:20.121784Z","steps":["trace[509416580] 'process raft request' (duration: 232.188403ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:20.121858Z","caller":"traceutil/trace.go:171","msg":"trace[441506751] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"232.852016ms","start":"2026-08-05T08:02:19.889001Z","end":"2026-08-05T08:02:20.121853Z","steps":["trace[441506751] 'process raft request' (duration: 232.135092ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:20.121925Z","caller":"traceutil/trace.go:171","msg":"trace[1289274265] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"232.807446ms","start":"2026-08-05T08:02:19.889112Z","end":"2026-08-05T08:02:20.12192Z","steps":["trace[1289274265] 'process raft request' (duration: 232.04422ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:20.121986Z","caller":"traceutil/trace.go:171","msg":"trace[864562342] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"232.651192ms","start":"2026-08-05T08:02:19.889331Z","end":"2026-08-05T08:02:20.121982Z","steps":["trace[864562342] 'process raft request' (duration: 231.858156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:20.122179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.104291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:17940"} {"level":"info","ts":"2026-08-05T08:02:20.1222Z","caller":"traceutil/trace.go:171","msg":"trace[1104642186] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25817; }","duration":"232.182623ms","start":"2026-08-05T08:02:19.890011Z","end":"2026-08-05T08:02:20.122194Z","steps":["trace[1104642186] 'agreement among raft nodes before linearized reading' (duration: 232.028199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:20.12233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.569069ms","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-08-05T08:02:20.122347Z","caller":"traceutil/trace.go:171","msg":"trace[615540872] 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:25817; }","duration":"180.63178ms","start":"2026-08-05T08:02:19.941709Z","end":"2026-08-05T08:02:20.12234Z","steps":["trace[615540872] 'agreement among raft nodes before linearized reading' (duration: 180.596079ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:22.503729Z","caller":"traceutil/trace.go:171","msg":"trace[1178148270] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"117.662723ms","start":"2026-08-05T08:02:22.386038Z","end":"2026-08-05T08:02:22.503701Z","steps":["trace[1178148270] 'process raft request' (duration: 117.52193ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:22.514614Z","caller":"traceutil/trace.go:171","msg":"trace[1085597697] linearizableReadLoop","detail":"{readStateIndex:26662; appliedIndex:26660; }","duration":"104.670658ms","start":"2026-08-05T08:02:22.409911Z","end":"2026-08-05T08:02:22.514582Z","steps":["trace[1085597697] 'read index received' (duration: 93.723775ms)","trace[1085597697] 'applied index is now lower than readState.Index' (duration: 10.945543ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:22.514895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.953044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T08:02:22.514945Z","caller":"traceutil/trace.go:171","msg":"trace[446810820] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:25833; }","duration":"105.077296ms","start":"2026-08-05T08:02:22.409855Z","end":"2026-08-05T08:02:22.514932Z","steps":["trace[446810820] 'agreement among raft nodes before linearized reading' (duration: 104.839322ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:22.514912Z","caller":"traceutil/trace.go:171","msg":"trace[1547224851] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"128.75289ms","start":"2026-08-05T08:02:22.386138Z","end":"2026-08-05T08:02:22.514891Z","steps":["trace[1547224851] 'process raft request' (duration: 128.258309ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:23.15431Z","caller":"traceutil/trace.go:171","msg":"trace[1803637783] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"286.660645ms","start":"2026-08-05T08:02:22.867615Z","end":"2026-08-05T08:02:23.154275Z","steps":["trace[1803637783] 'process raft request' (duration: 274.560968ms)","trace[1803637783] 'compare' (duration: 11.456354ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:23.70236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.912127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:02:23.702503Z","caller":"traceutil/trace.go:171","msg":"trace[1789676136] linearizableReadLoop","detail":"{readStateIndex:26667; appliedIndex:26666; }","duration":"208.656472ms","start":"2026-08-05T08:02:23.493822Z","end":"2026-08-05T08:02:23.702478Z","steps":["trace[1789676136] 'read index received' (duration: 139.283µs)","trace[1789676136] 'applied index is now lower than readState.Index' (duration: 208.515599ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:23.702597Z","caller":"traceutil/trace.go:171","msg":"trace[184797978] transaction","detail":"{read_only:false; response_revision:25838; number_of_response:1; }","duration":"379.715506ms","start":"2026-08-05T08:02:23.322866Z","end":"2026-08-05T08:02:23.702582Z","steps":["trace[184797978] 'process raft request' (duration: 123.463772ms)","trace[184797978] 'compare' (duration: 255.60131ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:23.702664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:23.322833Z","time spent":"379.799858ms","remote":"127.0.0.1:49928","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-08-05T08:02:23.702869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.03323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-05T08:02:23.702998Z","caller":"traceutil/trace.go:171","msg":"trace[583191976] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:25838; }","duration":"209.227863ms","start":"2026-08-05T08:02:23.493738Z","end":"2026-08-05T08:02:23.702966Z","steps":["trace[583191976] 'agreement among raft nodes before linearized reading' (duration: 208.937548ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:23.84407Z","caller":"traceutil/trace.go:171","msg":"trace[414957749] linearizableReadLoop","detail":"{readStateIndex:26670; appliedIndex:26667; }","duration":"141.49563ms","start":"2026-08-05T08:02:23.702535Z","end":"2026-08-05T08:02:23.84403Z","steps":["trace[414957749] 'read index received' (duration: 909.728µs)","trace[414957749] 'applied index is now lower than readState.Index' (duration: 140.584182ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:23.844146Z","caller":"traceutil/trace.go:171","msg":"trace[1123618478] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"225.196909ms","start":"2026-08-05T08:02:23.61892Z","end":"2026-08-05T08:02:23.844117Z","steps":["trace[1123618478] 'process raft request' (duration: 225.032986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:23.844308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.308812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:23.844391Z","caller":"traceutil/trace.go:171","msg":"trace[939617176] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"225.979495ms","start":"2026-08-05T08:02:23.618405Z","end":"2026-08-05T08:02:23.844385Z","steps":["trace[939617176] 'process raft request' (duration: 225.2182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:23.844442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.368654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:23.84439Z","caller":"traceutil/trace.go:171","msg":"trace[412687846] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25841; }","duration":"225.487295ms","start":"2026-08-05T08:02:23.618884Z","end":"2026-08-05T08:02:23.844371Z","steps":["trace[412687846] 'agreement among raft nodes before linearized reading' (duration: 225.316672ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:23.844477Z","caller":"traceutil/trace.go:171","msg":"trace[403716072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25841; }","duration":"274.463515ms","start":"2026-08-05T08:02:23.570005Z","end":"2026-08-05T08:02:23.844468Z","steps":["trace[403716072] 'agreement among raft nodes before linearized reading' (duration: 274.406534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:23.844497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.23077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:23.844606Z","caller":"traceutil/trace.go:171","msg":"trace[420401823] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25841; }","duration":"271.386393ms","start":"2026-08-05T08:02:23.573191Z","end":"2026-08-05T08:02:23.844577Z","steps":["trace[420401823] 'agreement among raft nodes before linearized reading' (duration: 271.187099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:23.85334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.650609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:23.85338Z","caller":"traceutil/trace.go:171","msg":"trace[1296503921] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25841; }","duration":"131.746541ms","start":"2026-08-05T08:02:23.721623Z","end":"2026-08-05T08:02:23.85337Z","steps":["trace[1296503921] 'agreement among raft nodes before linearized reading' (duration: 131.66014ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:24.027256Z","caller":"traceutil/trace.go:171","msg":"trace[1381319116] linearizableReadLoop","detail":"{readStateIndex:26673; appliedIndex:26672; }","duration":"129.953375ms","start":"2026-08-05T08:02:23.897273Z","end":"2026-08-05T08:02:24.027227Z","steps":["trace[1381319116] 'read index received' (duration: 128.078486ms)","trace[1381319116] 'applied index is now lower than readState.Index' (duration: 1.873939ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:24.027468Z","caller":"traceutil/trace.go:171","msg":"trace[949008225] transaction","detail":"{read_only:false; response_revision:25843; number_of_response:1; }","duration":"133.232292ms","start":"2026-08-05T08:02:23.894155Z","end":"2026-08-05T08:02:24.027388Z","steps":["trace[949008225] 'process raft request' (duration: 131.322743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:24.027694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.439058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:02:24.027734Z","caller":"traceutil/trace.go:171","msg":"trace[713591776] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25843; }","duration":"135.521889ms","start":"2026-08-05T08:02:23.892201Z","end":"2026-08-05T08:02:24.027722Z","steps":["trace[713591776] 'agreement among raft nodes before linearized reading' (duration: 135.193392ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:24.027837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.906614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-08-05T08:02:24.027891Z","caller":"traceutil/trace.go:171","msg":"trace[1372095251] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25843; }","duration":"129.014685ms","start":"2026-08-05T08:02:23.898862Z","end":"2026-08-05T08:02:24.027876Z","steps":["trace[1372095251] 'agreement among raft nodes before linearized reading' (duration: 128.812151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:30.724591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.667196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:02:30.724885Z","caller":"traceutil/trace.go:171","msg":"trace[1933714196] linearizableReadLoop","detail":"{readStateIndex:26721; appliedIndex:26718; }","duration":"322.810353ms","start":"2026-08-05T08:02:30.402049Z","end":"2026-08-05T08:02:30.72486Z","steps":["trace[1933714196] 'read index received' (duration: 128.642857ms)","trace[1933714196] 'applied index is now lower than readState.Index' (duration: 194.166206ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:30.724937Z","caller":"traceutil/trace.go:171","msg":"trace[462624629] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"377.196214ms","start":"2026-08-05T08:02:30.347698Z","end":"2026-08-05T08:02:30.724894Z","steps":["trace[462624629] 'process raft request' (duration: 377.039221ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:30.724966Z","caller":"traceutil/trace.go:171","msg":"trace[1968245218] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"377.598622ms","start":"2026-08-05T08:02:30.347306Z","end":"2026-08-05T08:02:30.724904Z","steps":["trace[1968245218] 'process raft request' (duration: 183.415776ms)","trace[1968245218] 'compare' (duration: 193.452851ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:30.725012Z","caller":"traceutil/trace.go:171","msg":"trace[732223928] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"376.779126ms","start":"2026-08-05T08:02:30.34817Z","end":"2026-08-05T08:02:30.724949Z","steps":["trace[732223928] 'process raft request' (duration: 376.635853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:30.725091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.107908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:30.725145Z","caller":"traceutil/trace.go:171","msg":"trace[247902119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25890; }","duration":"155.20376ms","start":"2026-08-05T08:02:30.569923Z","end":"2026-08-05T08:02:30.725127Z","steps":["trace[247902119] 'agreement among raft nodes before linearized reading' (duration: 155.092447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:30.72514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:30.347682Z","time spent":"377.340888ms","remote":"127.0.0.1:49928","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-08-05T08:02:30.725343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.832763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-05T08:02:30.725168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.310634ms","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-08-05T08:02:30.725387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:30.347282Z","time spent":"377.802357ms","remote":"127.0.0.1:49928","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-08-05T08:02:30.725444Z","caller":"traceutil/trace.go:171","msg":"trace[2038984221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25890; }","duration":"176.967876ms","start":"2026-08-05T08:02:30.54845Z","end":"2026-08-05T08:02:30.725418Z","steps":["trace[2038984221] 'agreement among raft nodes before linearized reading' (duration: 176.635368ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:30.725469Z","caller":"traceutil/trace.go:171","msg":"trace[1362533976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25890; }","duration":"183.630842ms","start":"2026-08-05T08:02:30.5418Z","end":"2026-08-05T08:02:30.72543Z","steps":["trace[1362533976] 'agreement among raft nodes before linearized reading' (duration: 183.199442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:30.725466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:30.348159Z","time spent":"376.97522ms","remote":"127.0.0.1:49928","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-08-05T08:02:30.725578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.440178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:02:30.725707Z","caller":"traceutil/trace.go:171","msg":"trace[544794660] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25890; }","duration":"323.669122ms","start":"2026-08-05T08:02:30.40201Z","end":"2026-08-05T08:02:30.725679Z","steps":["trace[544794660] 'agreement among raft nodes before linearized reading' (duration: 322.978307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:30.726171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T08:02:30.401986Z","time spent":"324.171622ms","remote":"127.0.0.1:49854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-05T08:02:32.250861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.757278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:32.250967Z","caller":"traceutil/trace.go:171","msg":"trace[1189517659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25898; }","duration":"144.935761ms","start":"2026-08-05T08:02:32.106006Z","end":"2026-08-05T08:02:32.250941Z","steps":["trace[1189517659] 'range keys from in-memory index tree' (duration: 144.573864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:54.130259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.705277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T08:02:54.130568Z","caller":"traceutil/trace.go:171","msg":"trace[2062168452] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"267.107416ms","start":"2026-08-05T08:02:53.863405Z","end":"2026-08-05T08:02:54.130512Z","steps":["trace[2062168452] 'process raft request' (duration: 143.024491ms)","trace[2062168452] 'compare' (duration: 123.517313ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:54.130715Z","caller":"traceutil/trace.go:171","msg":"trace[291714821] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"131.471325ms","start":"2026-08-05T08:02:53.999192Z","end":"2026-08-05T08:02:54.130663Z","steps":["trace[291714821] 'process raft request' (duration: 131.324022ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:54.13067Z","caller":"traceutil/trace.go:171","msg":"trace[1375461085] linearizableReadLoop","detail":"{readStateIndex:27055; appliedIndex:27053; }","duration":"151.228419ms","start":"2026-08-05T08:02:53.979416Z","end":"2026-08-05T08:02:54.130644Z","steps":["trace[1375461085] 'read index received' (duration: 27.090884ms)","trace[1375461085] 'applied index is now lower than readState.Index' (duration: 124.135765ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:02:54.130654Z","caller":"traceutil/trace.go:171","msg":"trace[430524019] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"154.223121ms","start":"2026-08-05T08:02:53.976395Z","end":"2026-08-05T08:02:54.130618Z","steps":["trace[430524019] 'process raft request' (duration: 154.012546ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:54.131003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.568867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-05T08:02:54.131084Z","caller":"traceutil/trace.go:171","msg":"trace[97814635] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:26218; }","duration":"151.72053ms","start":"2026-08-05T08:02:53.97935Z","end":"2026-08-05T08:02:54.131071Z","steps":["trace[97814635] 'agreement among raft nodes before linearized reading' (duration: 151.362083ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:54.131164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.780008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T08:02:54.131252Z","caller":"traceutil/trace.go:171","msg":"trace[92287723] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26218; }","duration":"143.999412ms","start":"2026-08-05T08:02:53.987224Z","end":"2026-08-05T08:02:54.131224Z","steps":["trace[92287723] 'agreement among raft nodes before linearized reading' (duration: 143.780387ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:54.334783Z","caller":"traceutil/trace.go:171","msg":"trace[1505584492] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"128.588826ms","start":"2026-08-05T08:02:54.206144Z","end":"2026-08-05T08:02:54.334733Z","steps":["trace[1505584492] 'process raft request' (duration: 39.513878ms)","trace[1505584492] 'compare' (duration: 88.695561ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T08:02:54.689667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.628333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-08-05T08:02:54.689767Z","caller":"traceutil/trace.go:171","msg":"trace[117955283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26219; }","duration":"213.758456ms","start":"2026-08-05T08:02:54.475985Z","end":"2026-08-05T08:02:54.689743Z","steps":["trace[117955283] 'range keys from in-memory index tree' (duration: 213.405599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:54.689892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.767086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T08:02:54.689916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.147088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T08:02:54.690035Z","caller":"traceutil/trace.go:171","msg":"trace[736886436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26219; }","duration":"119.982801ms","start":"2026-08-05T08:02:54.570021Z","end":"2026-08-05T08:02:54.690003Z","steps":["trace[736886436] 'range keys from in-memory index tree' (duration: 119.647824ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:02:54.690051Z","caller":"traceutil/trace.go:171","msg":"trace[1500712643] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:26219; }","duration":"247.360033ms","start":"2026-08-05T08:02:54.442657Z","end":"2026-08-05T08:02:54.690017Z","steps":["trace[1500712643] 'count revisions from in-memory index tree' (duration: 247.011995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T08:02:54.689935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.829448ms","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-08-05T08:02:54.690325Z","caller":"traceutil/trace.go:171","msg":"trace[2075115868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26219; }","duration":"142.282617ms","start":"2026-08-05T08:02:54.54803Z","end":"2026-08-05T08:02:54.690312Z","steps":["trace[2075115868] 'range keys from in-memory index tree' (duration: 141.563731ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T08:03:10.37387Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23180} {"level":"info","ts":"2026-08-05T08:03:10.502615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23180,"took":"123.103435ms","hash":4260651430,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":42860544,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-05T08:03:10.502684Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4260651430,"revision":23180,"compact-revision":20365} {"level":"info","ts":"2026-08-05T08:08:04.50373Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T08:08:04.508901Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-05T08:08:04.509704Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-05T08:08:10.380086Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26367} {"level":"info","ts":"2026-08-05T08:08:10.602179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26367,"took":"217.684104ms","hash":397952561,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":36433920,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-05T08:08:10.602235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":397952561,"revision":26367,"compact-revision":23180} {"level":"info","ts":"2026-08-05T08:13:10.386999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29134} {"level":"info","ts":"2026-08-05T08:13:10.54214Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29134,"took":"150.614545ms","hash":487002240,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":31428608,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T08:13:10.542427Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":487002240,"revision":29134,"compact-revision":26367} {"level":"info","ts":"2026-08-05T08:18:10.393456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31401} {"level":"info","ts":"2026-08-05T08:18:10.521361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31401,"took":"122.809577ms","hash":2945566704,"current-db-size-bytes":58331136,"current-db-size":"58 MB","current-db-size-in-use-bytes":31481856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T08:18:10.521448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2945566704,"revision":31401,"compact-revision":29134} {"level":"info","ts":"2026-08-05T08:23:10.404715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33707} {"level":"info","ts":"2026-08-05T08:23:10.548037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33707,"took":"137.15773ms","hash":3303320414,"current-db-size-bytes":58757120,"current-db-size":"59 MB","current-db-size-in-use-bytes":32985088,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-05T08:23:10.548104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3303320414,"revision":33707,"compact-revision":31401} {"level":"info","ts":"2026-08-05T08:28:10.410253Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36040} {"level":"info","ts":"2026-08-05T08:28:10.555675Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36040,"took":"140.859627ms","hash":3055941799,"current-db-size-bytes":60358656,"current-db-size":"60 MB","current-db-size-in-use-bytes":32030720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T08:28:10.555732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3055941799,"revision":36040,"compact-revision":33707} {"level":"info","ts":"2026-08-05T08:29:20.092542Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b844c110b9783e73","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T08:29:20.098094Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-05T08:29:20.098222Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-05T08:33:10.453621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38250} {"level":"info","ts":"2026-08-05T08:33:10.904289Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38250,"took":"446.03771ms","hash":1003210134,"current-db-size-bytes":60358656,"current-db-size":"60 MB","current-db-size-in-use-bytes":31006720,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T08:33:10.904371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1003210134,"revision":38250,"compact-revision":36040} {"level":"info","ts":"2026-08-05T08:33:16.855292Z","caller":"traceutil/trace.go:171","msg":"trace[1155465475] transaction","detail":"{read_only:false; response_revision:40554; number_of_response:1; }","duration":"131.2587ms","start":"2026-08-05T08:33:16.724001Z","end":"2026-08-05T08:33:16.85526Z","steps":["trace[1155465475] 'process raft request' (duration: 116.930778ms)","trace[1155465475] 'compare' (duration: 14.1998ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T08:38:10.458838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40504} {"level":"info","ts":"2026-08-05T08:38:10.585159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40504,"took":"121.323257ms","hash":4263146490,"current-db-size-bytes":60358656,"current-db-size":"60 MB","current-db-size-in-use-bytes":31125504,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T08:38:10.585251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4263146490,"revision":40504,"compact-revision":38250}