{"level":"warn","ts":"2026-08-20T01:14:59.695397Z","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-20T01:14:59.695483Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-20T01:14:59.695543Z","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-20T01:14:59.695557Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-08-20T01:14:59.695573Z","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-20T01:14:59.696173Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-08-20T01:14:59.696247Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-20T01:14:59.700389Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.96407ms"} {"level":"info","ts":"2026-08-20T01:14:59.710046Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-08-20T01:14:59.710217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T01:14:59.710253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-08-20T01:14:59.71027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T01:14:59.710288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-08-20T01:14:59.710347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-08-20T01:14:59.721427Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T01:14:59.723015Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T01:14:59.724809Z","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-20T01:14:59.726883Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T01:14:59.727125Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T01:14:59.727374Z","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-20T01:14:59.727533Z","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-20T01:14:59.727599Z","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-20T01:14:59.728436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-08-20T01:14:59.729352Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-08-20T01:14:59.732144Z","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-20T01:14:59.732326Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-08-20T01:14:59.732386Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-08-20T01:14:59.732491Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T01:14:59.732563Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T01:14:59.911715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T01:14:59.911808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T01:14:59.911842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-08-20T01:14:59.911867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-08-20T01:14:59.91188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-08-20T01:14:59.911899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-08-20T01:14:59.911916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-08-20T01:14:59.913168Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:14:59.915153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:14:59.915166Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T01:14:59.915177Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:14:59.915559Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T01:14:59.915598Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T01:14:59.916241Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:14:59.916405Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:14:59.916441Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:14:59.917758Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T01:14:59.919042Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-08-20T01:15:24.086631Z","caller":"traceutil/trace.go:171","msg":"trace[457670427] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"150.054737ms","start":"2026-08-20T01:15:23.93655Z","end":"2026-08-20T01:15:24.086605Z","steps":["trace[457670427] 'process raft request' (duration: 149.893863ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:37.310451Z","caller":"traceutil/trace.go:171","msg":"trace[292939414] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"112.118149ms","start":"2026-08-20T01:15:37.198304Z","end":"2026-08-20T01:15:37.310422Z","steps":["trace[292939414] 'process raft request' (duration: 111.974985ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:37.317122Z","caller":"traceutil/trace.go:171","msg":"trace[984985605] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"110.076582ms","start":"2026-08-20T01:15:37.207001Z","end":"2026-08-20T01:15:37.317077Z","steps":["trace[984985605] 'process raft request' (duration: 109.882497ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:37.317517Z","caller":"traceutil/trace.go:171","msg":"trace[1179000711] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"100.682253ms","start":"2026-08-20T01:15:37.216824Z","end":"2026-08-20T01:15:37.317506Z","steps":["trace[1179000711] 'process raft request' (duration: 100.291862ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:37.317707Z","caller":"traceutil/trace.go:171","msg":"trace[931001543] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"109.746173ms","start":"2026-08-20T01:15:37.207949Z","end":"2026-08-20T01:15:37.317695Z","steps":["trace[931001543] 'process raft request' (duration: 109.039993ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:37.317852Z","caller":"traceutil/trace.go:171","msg":"trace[189491432] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"101.823645ms","start":"2026-08-20T01:15:37.216002Z","end":"2026-08-20T01:15:37.317826Z","steps":["trace[189491432] 'process raft request' (duration: 101.033873ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:37.318036Z","caller":"traceutil/trace.go:171","msg":"trace[541667726] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"101.66271ms","start":"2026-08-20T01:15:37.216364Z","end":"2026-08-20T01:15:37.318027Z","steps":["trace[541667726] 'process raft request' (duration: 100.710894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:43.593705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.049676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:15:43.593923Z","caller":"traceutil/trace.go:171","msg":"trace[1833576549] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"218.422516ms","start":"2026-08-20T01:15:43.37547Z","end":"2026-08-20T01:15:43.593893Z","steps":["trace[1833576549] 'process raft request' (duration: 16.447664ms)","trace[1833576549] 'compare' (duration: 200.868131ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:15:43.721058Z","caller":"traceutil/trace.go:171","msg":"trace[1903765475] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"204.832222ms","start":"2026-08-20T01:15:43.51618Z","end":"2026-08-20T01:15:43.721012Z","steps":["trace[1903765475] 'process raft request' (duration: 204.090131ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:44.054922Z","caller":"traceutil/trace.go:171","msg":"trace[1148258726] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"276.942835ms","start":"2026-08-20T01:15:43.777936Z","end":"2026-08-20T01:15:44.054878Z","steps":["trace[1148258726] 'process raft request' (duration: 276.633926ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:44.198973Z","caller":"traceutil/trace.go:171","msg":"trace[1836484101] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"127.828103ms","start":"2026-08-20T01:15:44.071119Z","end":"2026-08-20T01:15:44.198947Z","steps":["trace[1836484101] 'process raft request' (duration: 123.864764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:59.649363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.19057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-20T01:15:59.649471Z","caller":"traceutil/trace.go:171","msg":"trace[1434151366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"164.419856ms","start":"2026-08-20T01:15:59.485021Z","end":"2026-08-20T01:15:59.649441Z","steps":["trace[1434151366] 'range keys from in-memory index tree' (duration: 163.985233ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:14.325972Z","caller":"traceutil/trace.go:171","msg":"trace[597713622] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"181.267168ms","start":"2026-08-20T01:17:14.144678Z","end":"2026-08-20T01:17:14.325946Z","steps":["trace[597713622] 'read index received' (duration: 181.148275ms)","trace[597713622] 'applied index is now lower than readState.Index' (duration: 117.603µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:17:14.326086Z","caller":"traceutil/trace.go:171","msg":"trace[315222049] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"221.734965ms","start":"2026-08-20T01:17:14.104313Z","end":"2026-08-20T01:17:14.326048Z","steps":["trace[315222049] 'process raft request' (duration: 221.481828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:17:14.326211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.524215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f2e933e1-4b01-4e6a-9f99-7f101828c4d4\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-20T01:17:14.326266Z","caller":"traceutil/trace.go:171","msg":"trace[830687094] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f2e933e1-4b01-4e6a-9f99-7f101828c4d4; range_end:; response_count:1; response_revision:1814; }","duration":"181.622068ms","start":"2026-08-20T01:17:14.144628Z","end":"2026-08-20T01:17:14.32625Z","steps":["trace[830687094] 'agreement among raft nodes before linearized reading' (duration: 181.469413ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:14.435068Z","caller":"traceutil/trace.go:171","msg":"trace[1537853430] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"102.686539ms","start":"2026-08-20T01:17:14.332356Z","end":"2026-08-20T01:17:14.435042Z","steps":["trace[1537853430] 'process raft request' (duration: 102.624908ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:14.435235Z","caller":"traceutil/trace.go:171","msg":"trace[1881194393] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"104.586513ms","start":"2026-08-20T01:17:14.330622Z","end":"2026-08-20T01:17:14.435209Z","steps":["trace[1881194393] 'process raft request' (duration: 29.753749ms)","trace[1881194393] 'compare' (duration: 74.402322ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:17:29.775438Z","caller":"traceutil/trace.go:171","msg":"trace[830510958] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"161.902769ms","start":"2026-08-20T01:17:29.613156Z","end":"2026-08-20T01:17:29.775059Z","steps":["trace[830510958] 'process raft request' (duration: 158.983017ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:18:03.99997Z","caller":"traceutil/trace.go:171","msg":"trace[460248922] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"112.142432ms","start":"2026-08-20T01:18:03.887786Z","end":"2026-08-20T01:18:03.999928Z","steps":["trace[460248922] 'process raft request' (duration: 72.025885ms)","trace[460248922] 'compare' (duration: 39.832789ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:18:39.6491Z","caller":"traceutil/trace.go:171","msg":"trace[1057300894] linearizableReadLoop","detail":"{readStateIndex:2467; appliedIndex:2466; }","duration":"149.122961ms","start":"2026-08-20T01:18:39.499947Z","end":"2026-08-20T01:18:39.64907Z","steps":["trace[1057300894] 'read index received' (duration: 148.874474ms)","trace[1057300894] 'applied index is now lower than readState.Index' (duration: 247.277µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:18:39.649163Z","caller":"traceutil/trace.go:171","msg":"trace[2104284308] transaction","detail":"{read_only:false; response_revision:2378; number_of_response:1; }","duration":"195.781164ms","start":"2026-08-20T01:18:39.45336Z","end":"2026-08-20T01:18:39.649141Z","steps":["trace[2104284308] 'process raft request' (duration: 195.527367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:18:39.649286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.311357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:18:39.64933Z","caller":"traceutil/trace.go:171","msg":"trace[1497517139] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2378; }","duration":"149.397159ms","start":"2026-08-20T01:18:39.499921Z","end":"2026-08-20T01:18:39.649318Z","steps":["trace[1497517139] 'agreement among raft nodes before linearized reading' (duration: 149.288526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:18:39.652157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.960119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-08-20T01:18:39.65221Z","caller":"traceutil/trace.go:171","msg":"trace[1436485043] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2379; }","duration":"134.049802ms","start":"2026-08-20T01:18:39.518147Z","end":"2026-08-20T01:18:39.652197Z","steps":["trace[1436485043] 'agreement among raft nodes before linearized reading' (duration: 133.874827ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:18:39.652264Z","caller":"traceutil/trace.go:171","msg":"trace[648965322] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"142.926579ms","start":"2026-08-20T01:18:39.509307Z","end":"2026-08-20T01:18:39.652233Z","steps":["trace[648965322] 'process raft request' (duration: 142.60718ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:19:11.11913Z","caller":"traceutil/trace.go:171","msg":"trace[1894690426] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"122.119751ms","start":"2026-08-20T01:19:10.996977Z","end":"2026-08-20T01:19:11.119097Z","steps":["trace[1894690426] 'process raft request' (duration: 109.592782ms)","trace[1894690426] 'compare' (duration: 12.391895ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:21:53.44816Z","caller":"traceutil/trace.go:171","msg":"trace[1634797222] linearizableReadLoop","detail":"{readStateIndex:3727; appliedIndex:3726; }","duration":"123.932607ms","start":"2026-08-20T01:21:53.324207Z","end":"2026-08-20T01:21:53.44814Z","steps":["trace[1634797222] 'read index received' (duration: 123.836225ms)","trace[1634797222] 'applied index is now lower than readState.Index' (duration: 95.712µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:21:53.448206Z","caller":"traceutil/trace.go:171","msg":"trace[743826063] transaction","detail":"{read_only:false; response_revision:3592; number_of_response:1; }","duration":"183.94142ms","start":"2026-08-20T01:21:53.264234Z","end":"2026-08-20T01:21:53.448176Z","steps":["trace[743826063] 'process raft request' (duration: 183.775676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:21:53.448295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.067541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:21:53.448323Z","caller":"traceutil/trace.go:171","msg":"trace[2100246516] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:3592; }","duration":"124.155113ms","start":"2026-08-20T01:21:53.324159Z","end":"2026-08-20T01:21:53.448314Z","steps":["trace[2100246516] 'agreement among raft nodes before linearized reading' (duration: 124.089902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:21:53.448481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.553395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-20T01:21:53.448511Z","caller":"traceutil/trace.go:171","msg":"trace[1271982251] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:3592; }","duration":"109.637559ms","start":"2026-08-20T01:21:53.338866Z","end":"2026-08-20T01:21:53.448504Z","steps":["trace[1271982251] 'agreement among raft nodes before linearized reading' (duration: 109.510615ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:16.573878Z","caller":"traceutil/trace.go:171","msg":"trace[2002645623] linearizableReadLoop","detail":"{readStateIndex:4921; appliedIndex:4920; }","duration":"121.668333ms","start":"2026-08-20T01:23:16.452169Z","end":"2026-08-20T01:23:16.573837Z","steps":["trace[2002645623] 'read index received' (duration: 121.433157ms)","trace[2002645623] 'applied index is now lower than readState.Index' (duration: 234.456µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:23:16.574181Z","caller":"traceutil/trace.go:171","msg":"trace[359726594] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"236.973379ms","start":"2026-08-20T01:23:16.337192Z","end":"2026-08-20T01:23:16.574166Z","steps":["trace[359726594] 'process raft request' (duration: 236.493186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:16.574379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.110415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:23:16.574464Z","caller":"traceutil/trace.go:171","msg":"trace[1956531183] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4742; }","duration":"122.219369ms","start":"2026-08-20T01:23:16.452222Z","end":"2026-08-20T01:23:16.574442Z","steps":["trace[1956531183] 'agreement among raft nodes before linearized reading' (duration: 122.090355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:16.574805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.63151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-20T01:23:16.574849Z","caller":"traceutil/trace.go:171","msg":"trace[988634076] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4742; }","duration":"122.677431ms","start":"2026-08-20T01:23:16.452161Z","end":"2026-08-20T01:23:16.574839Z","steps":["trace[988634076] 'agreement among raft nodes before linearized reading' (duration: 122.545767ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:31.281642Z","caller":"traceutil/trace.go:171","msg":"trace[932865032] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"213.329532ms","start":"2026-08-20T01:23:31.068277Z","end":"2026-08-20T01:23:31.281606Z","steps":["trace[932865032] 'process raft request' (duration: 213.172347ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:31.326222Z","caller":"traceutil/trace.go:171","msg":"trace[1184368214] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"106.517222ms","start":"2026-08-20T01:23:31.219665Z","end":"2026-08-20T01:23:31.326182Z","steps":["trace[1184368214] 'process raft request' (duration: 106.240244ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:38.467122Z","caller":"traceutil/trace.go:171","msg":"trace[715473226] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"111.870861ms","start":"2026-08-20T01:23:38.355222Z","end":"2026-08-20T01:23:38.467092Z","steps":["trace[715473226] 'process raft request' (duration: 111.683836ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:25:00.232338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2809} {"level":"info","ts":"2026-08-20T01:25:00.301512Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2809,"took":"67.36808ms","hash":3440814866,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":33173504,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-20T01:25:00.30167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3440814866,"revision":2809,"compact-revision":-1} {"level":"warn","ts":"2026-08-20T01:29:31.081965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.3419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207048"} {"level":"info","ts":"2026-08-20T01:29:31.082061Z","caller":"traceutil/trace.go:171","msg":"trace[538811459] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7937; }","duration":"283.463663ms","start":"2026-08-20T01:29:30.798577Z","end":"2026-08-20T01:29:31.08204Z","steps":["trace[538811459] 'range keys from in-memory index tree' (duration: 283.009201ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:42.864499Z","caller":"traceutil/trace.go:171","msg":"trace[1323622476] transaction","detail":"{read_only:false; response_revision:7996; number_of_response:1; }","duration":"189.278677ms","start":"2026-08-20T01:29:42.675187Z","end":"2026-08-20T01:29:42.864466Z","steps":["trace[1323622476] 'process raft request' (duration: 189.133093ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:42.86452Z","caller":"traceutil/trace.go:171","msg":"trace[1665612102] linearizableReadLoop","detail":"{readStateIndex:8278; appliedIndex:8278; }","duration":"187.969691ms","start":"2026-08-20T01:29:42.676505Z","end":"2026-08-20T01:29:42.864474Z","steps":["trace[1665612102] 'read index received' (duration: 187.94751ms)","trace[1665612102] 'applied index is now lower than readState.Index' (duration: 18.8µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:29:42.864765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.239309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-20T01:29:42.864849Z","caller":"traceutil/trace.go:171","msg":"trace[404671971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7996; }","duration":"188.392133ms","start":"2026-08-20T01:29:42.676439Z","end":"2026-08-20T01:29:42.864832Z","steps":["trace[404671971] 'agreement among raft nodes before linearized reading' (duration: 188.137695ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:42.876045Z","caller":"traceutil/trace.go:171","msg":"trace[1508355958] transaction","detail":"{read_only:false; response_revision:7997; number_of_response:1; }","duration":"187.805386ms","start":"2026-08-20T01:29:42.688227Z","end":"2026-08-20T01:29:42.876033Z","steps":["trace[1508355958] 'process raft request' (duration: 187.718274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:29:42.876182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.017522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-20T01:29:42.876216Z","caller":"traceutil/trace.go:171","msg":"trace[505752689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7997; }","duration":"188.081944ms","start":"2026-08-20T01:29:42.688126Z","end":"2026-08-20T01:29:42.876208Z","steps":["trace[505752689] 'agreement among raft nodes before linearized reading' (duration: 187.957191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:29:42.876332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.131497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-20T01:29:42.87639Z","caller":"traceutil/trace.go:171","msg":"trace[384100840] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7997; }","duration":"118.25667ms","start":"2026-08-20T01:29:42.758115Z","end":"2026-08-20T01:29:42.876372Z","steps":["trace[384100840] 'agreement among raft nodes before linearized reading' (duration: 118.013103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:29:42.876423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.813167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-20T01:29:42.876521Z","caller":"traceutil/trace.go:171","msg":"trace[1606315796] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7997; }","duration":"122.944981ms","start":"2026-08-20T01:29:42.753545Z","end":"2026-08-20T01:29:42.87649Z","steps":["trace[1606315796] 'agreement among raft nodes before linearized reading' (duration: 122.635492ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:43.659483Z","caller":"traceutil/trace.go:171","msg":"trace[2116246778] linearizableReadLoop","detail":"{readStateIndex:8285; appliedIndex:8284; }","duration":"211.638823ms","start":"2026-08-20T01:29:43.447806Z","end":"2026-08-20T01:29:43.659445Z","steps":["trace[2116246778] 'read index received' (duration: 211.399737ms)","trace[2116246778] 'applied index is now lower than readState.Index' (duration: 237.716µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:29:43.6597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.85406ms","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:7"} {"level":"info","ts":"2026-08-20T01:29:43.659812Z","caller":"traceutil/trace.go:171","msg":"trace[976741489] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8002; }","duration":"212.033785ms","start":"2026-08-20T01:29:43.447753Z","end":"2026-08-20T01:29:43.659787Z","steps":["trace[976741489] 'agreement among raft nodes before linearized reading' (duration: 211.814069ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:43.793549Z","caller":"traceutil/trace.go:171","msg":"trace[796417361] transaction","detail":"{read_only:false; response_revision:8003; number_of_response:1; }","duration":"132.407545ms","start":"2026-08-20T01:29:43.66111Z","end":"2026-08-20T01:29:43.793517Z","steps":["trace[796417361] 'process raft request' (duration: 93.64443ms)","trace[796417361] 'compare' (duration: 38.598011ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:30:00.239162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5900} {"level":"info","ts":"2026-08-20T01:30:00.319294Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5900,"took":"77.858089ms","hash":3255190579,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":25841664,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-20T01:30:00.319373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3255190579,"revision":5900,"compact-revision":2809} {"level":"info","ts":"2026-08-20T01:30:10.521994Z","caller":"traceutil/trace.go:171","msg":"trace[848260159] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"121.699516ms","start":"2026-08-20T01:30:10.400256Z","end":"2026-08-20T01:30:10.521956Z","steps":["trace[848260159] 'process raft request' (duration: 121.513501ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:30:10.578149Z","caller":"traceutil/trace.go:171","msg":"trace[810343408] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"117.987472ms","start":"2026-08-20T01:30:10.460104Z","end":"2026-08-20T01:30:10.578092Z","steps":["trace[810343408] 'process raft request' (duration: 117.708104ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:30:24.348629Z","caller":"traceutil/trace.go:171","msg":"trace[1349277368] transaction","detail":"{read_only:false; response_revision:8314; number_of_response:1; }","duration":"403.415941ms","start":"2026-08-20T01:30:23.945184Z","end":"2026-08-20T01:30:24.3486Z","steps":["trace[1349277368] 'process raft request' (duration: 403.065661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:30:24.348785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:30:23.945167Z","time spent":"403.549634ms","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:30:24.391111Z","caller":"traceutil/trace.go:171","msg":"trace[1518601671] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"435.633202ms","start":"2026-08-20T01:30:23.955447Z","end":"2026-08-20T01:30:24.39108Z","steps":["trace[1518601671] 'process raft request' (duration: 425.021175ms)","trace[1518601671] 'compare' (duration: 10.432522ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:30:24.391243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:30:23.95542Z","time spent":"435.755196ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:30:25.184802Z","caller":"traceutil/trace.go:171","msg":"trace[247114464] transaction","detail":"{read_only:false; response_revision:8319; number_of_response:1; }","duration":"114.839634ms","start":"2026-08-20T01:30:25.06994Z","end":"2026-08-20T01:30:25.18478Z","steps":["trace[247114464] 'process raft request' (duration: 114.67693ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:31:11.317823Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a1.wal"} {"level":"info","ts":"2026-08-20T01:31:33.115287Z","caller":"traceutil/trace.go:171","msg":"trace[1918137488] transaction","detail":"{read_only:false; response_revision:8961; number_of_response:1; }","duration":"172.420326ms","start":"2026-08-20T01:31:32.942828Z","end":"2026-08-20T01:31:33.115248Z","steps":["trace[1918137488] 'process raft request' (duration: 96.006857ms)","trace[1918137488] 'compare' (duration: 76.180282ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:32:56.288339Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T01:32:56.29265Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-20T01:32:56.292735Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-20T01:35:00.246119Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8094} {"level":"info","ts":"2026-08-20T01:35:00.316788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8094,"took":"67.297843ms","hash":4086819675,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":30724096,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-20T01:35:00.316859Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4086819675,"revision":8094,"compact-revision":5900} {"level":"info","ts":"2026-08-20T01:40:00.256823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10816} {"level":"info","ts":"2026-08-20T01:40:00.341266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10816,"took":"81.655255ms","hash":2104612737,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":27533312,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-20T01:40:00.34134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2104612737,"revision":10816,"compact-revision":8094} {"level":"info","ts":"2026-08-20T01:41:51.98633Z","caller":"traceutil/trace.go:171","msg":"trace[2000783031] transaction","detail":"{read_only:false; response_revision:13552; number_of_response:1; }","duration":"209.408651ms","start":"2026-08-20T01:41:51.776889Z","end":"2026-08-20T01:41:51.986298Z","steps":["trace[2000783031] 'process raft request' (duration: 209.226616ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:43:11.050733Z","caller":"traceutil/trace.go:171","msg":"trace[33684083] transaction","detail":"{read_only:false; response_revision:14511; number_of_response:1; }","duration":"120.654319ms","start":"2026-08-20T01:43:10.93005Z","end":"2026-08-20T01:43:11.050705Z","steps":["trace[33684083] 'process raft request' (duration: 120.177275ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:45:00.264767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12849} {"level":"info","ts":"2026-08-20T01:45:00.343441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12849,"took":"76.036163ms","hash":1011654799,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":30179328,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-20T01:45:00.343532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1011654799,"revision":12849,"compact-revision":10816} {"level":"info","ts":"2026-08-20T01:48:18.341885Z","caller":"traceutil/trace.go:171","msg":"trace[1136348616] transaction","detail":"{read_only:false; response_revision:16766; number_of_response:1; }","duration":"343.50393ms","start":"2026-08-20T01:48:17.998347Z","end":"2026-08-20T01:48:18.341851Z","steps":["trace[1136348616] 'process raft request' (duration: 343.126119ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:48:18.341936Z","caller":"traceutil/trace.go:171","msg":"trace[1939696060] linearizableReadLoop","detail":"{readStateIndex:17334; appliedIndex:17333; }","duration":"343.408697ms","start":"2026-08-20T01:48:17.998491Z","end":"2026-08-20T01:48:18.3419Z","steps":["trace[1939696060] 'read index received' (duration: 342.985625ms)","trace[1939696060] 'applied index is now lower than readState.Index' (duration: 419.572µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:48:18.342078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998337Z","time spent":"343.653784ms","remote":"127.0.0.1:53846","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-20T01:48:18.342114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.695075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kx69m\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-08-20T01:48:18.342196Z","caller":"traceutil/trace.go:171","msg":"trace[1666525822] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kx69m; range_end:; response_count:1; response_revision:16767; }","duration":"343.794978ms","start":"2026-08-20T01:48:17.998379Z","end":"2026-08-20T01:48:18.342174Z","steps":["trace[1666525822] 'agreement among raft nodes before linearized reading' (duration: 343.556441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.342185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.826209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"warn","ts":"2026-08-20T01:48:18.342239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998372Z","time spent":"343.857459ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9820,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-kx69m\" "} {"level":"warn","ts":"2026-08-20T01:48:18.342245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.909851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bhqcz\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-08-20T01:48:18.342272Z","caller":"traceutil/trace.go:171","msg":"trace[512265305] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16767; }","duration":"343.934012ms","start":"2026-08-20T01:48:17.998315Z","end":"2026-08-20T01:48:18.342249Z","steps":["trace[512265305] 'agreement among raft nodes before linearized reading' (duration: 343.665884ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.342319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998313Z","time spent":"343.998324ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-08-20T01:48:18.342289Z","caller":"traceutil/trace.go:171","msg":"trace[558734645] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bhqcz; range_end:; response_count:1; response_revision:16767; }","duration":"343.979133ms","start":"2026-08-20T01:48:17.998296Z","end":"2026-08-20T01:48:18.342275Z","steps":["trace[558734645] 'agreement among raft nodes before linearized reading' (duration: 343.763447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.342417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.970123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7384"} {"level":"info","ts":"2026-08-20T01:48:18.342475Z","caller":"traceutil/trace.go:171","msg":"trace[811250531] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:16767; }","duration":"344.039545ms","start":"2026-08-20T01:48:17.998419Z","end":"2026-08-20T01:48:18.342458Z","steps":["trace[811250531] 'agreement among raft nodes before linearized reading' (duration: 343.670584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.342513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998414Z","time spent":"344.091476ms","remote":"127.0.0.1:53818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":7407,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"warn","ts":"2026-08-20T01:48:18.342518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.083146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5mkzx\" ","response":"range_response_count:1 size:6532"} {"level":"info","ts":"2026-08-20T01:48:18.342561Z","caller":"traceutil/trace.go:171","msg":"trace[836101146] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5mkzx; range_end:; response_count:1; response_revision:16767; }","duration":"344.136247ms","start":"2026-08-20T01:48:17.998413Z","end":"2026-08-20T01:48:18.34255Z","steps":["trace[836101146] 'agreement among raft nodes before linearized reading' (duration: 344.026234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.342569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.108526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9jtqz\" ","response":"range_response_count:1 size:20660"} {"level":"warn","ts":"2026-08-20T01:48:18.342589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998398Z","time spent":"344.185159ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6555,"request content":"key:\"/registry/pods/openstack/keepalived-5mkzx\" "} {"level":"info","ts":"2026-08-20T01:48:18.342608Z","caller":"traceutil/trace.go:171","msg":"trace[656227055] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-9jtqz; range_end:; response_count:1; response_revision:16767; }","duration":"344.161339ms","start":"2026-08-20T01:48:17.998434Z","end":"2026-08-20T01:48:18.342596Z","steps":["trace[656227055] 'agreement among raft nodes before linearized reading' (duration: 344.030195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.342633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.327942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-20T01:48:18.342693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998429Z","time spent":"344.20245ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20683,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-9jtqz\" "} {"level":"info","ts":"2026-08-20T01:48:18.342704Z","caller":"traceutil/trace.go:171","msg":"trace[2104799695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16767; }","duration":"344.409795ms","start":"2026-08-20T01:48:17.998283Z","end":"2026-08-20T01:48:18.342693Z","steps":["trace[2104799695] 'agreement among raft nodes before linearized reading' (duration: 344.264051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.342718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.305703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7384"} {"level":"warn","ts":"2026-08-20T01:48:18.34274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.99828Z","time spent":"344.453146ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-20T01:48:18.342459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998291Z","time spent":"344.156928ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22156,"request content":"key:\"/registry/pods/openstack/nova-compute-default-bhqcz\" "} {"level":"warn","ts":"2026-08-20T01:48:18.3427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.336813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"warn","ts":"2026-08-20T01:48:18.342779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.436846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-08-20T01:48:18.342769Z","caller":"traceutil/trace.go:171","msg":"trace[412406823] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:16767; }","duration":"344.397575ms","start":"2026-08-20T01:48:17.998356Z","end":"2026-08-20T01:48:18.342754Z","steps":["trace[412406823] 'agreement among raft nodes before linearized reading' (duration: 344.237841ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:48:18.342802Z","caller":"traceutil/trace.go:171","msg":"trace[667499431] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16767; }","duration":"344.447196ms","start":"2026-08-20T01:48:17.998343Z","end":"2026-08-20T01:48:18.34279Z","steps":["trace[667499431] 'agreement among raft nodes before linearized reading' (duration: 344.24526ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:48:18.342812Z","caller":"traceutil/trace.go:171","msg":"trace[988816536] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16767; }","duration":"344.471536ms","start":"2026-08-20T01:48:17.99833Z","end":"2026-08-20T01:48:18.342802Z","steps":["trace[988816536] 'agreement among raft nodes before linearized reading' (duration: 344.392164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.342839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998339Z","time spent":"344.488947ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-08-20T01:48:18.342834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998339Z","time spent":"344.479477ms","remote":"127.0.0.1:53818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":7407,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"warn","ts":"2026-08-20T01:48:18.342843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998328Z","time spent":"344.509008ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-08-20T01:48:18.343095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.814286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-08-20T01:48:18.343132Z","caller":"traceutil/trace.go:171","msg":"trace[1960578658] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16767; }","duration":"344.891318ms","start":"2026-08-20T01:48:17.998231Z","end":"2026-08-20T01:48:18.343123Z","steps":["trace[1960578658] 'agreement among raft nodes before linearized reading' (duration: 344.783885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.343362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998215Z","time spent":"345.136406ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-08-20T01:48:18.343562Z","caller":"traceutil/trace.go:171","msg":"trace[484433137] transaction","detail":"{read_only:false; response_revision:16767; number_of_response:1; }","duration":"344.849118ms","start":"2026-08-20T01:48:17.998677Z","end":"2026-08-20T01:48:18.343526Z","steps":["trace[484433137] 'process raft request' (duration: 343.104859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.343731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998657Z","time spent":"344.95847ms","remote":"127.0.0.1:53846","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-20T01:48:18.34475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.215395ms","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-20T01:48:18.344812Z","caller":"traceutil/trace.go:171","msg":"trace[244377995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16768; }","duration":"346.288128ms","start":"2026-08-20T01:48:17.998509Z","end":"2026-08-20T01:48:18.344797Z","steps":["trace[244377995] 'agreement among raft nodes before linearized reading' (duration: 346.125223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.344846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998502Z","time spent":"346.336749ms","remote":"127.0.0.1:53846","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":"warn","ts":"2026-08-20T01:48:18.344943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.36809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T01:48:18.344982Z","caller":"traceutil/trace.go:171","msg":"trace[244853256] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:16768; }","duration":"346.425722ms","start":"2026-08-20T01:48:17.998545Z","end":"2026-08-20T01:48:18.344971Z","steps":["trace[244853256] 'agreement among raft nodes before linearized reading' (duration: 346.321189ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.345005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998539Z","time spent":"346.459513ms","remote":"127.0.0.1:54086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":42,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-20T01:48:18.345053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.489453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T01:48:18.345087Z","caller":"traceutil/trace.go:171","msg":"trace[1311833046] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16768; }","duration":"346.525044ms","start":"2026-08-20T01:48:17.998549Z","end":"2026-08-20T01:48:18.345074Z","steps":["trace[1311833046] 'agreement among raft nodes before linearized reading' (duration: 346.433031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.345114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998545Z","time spent":"346.563426ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-20T01:48:18.345264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.399205ms","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-20T01:48:18.345288Z","caller":"traceutil/trace.go:171","msg":"trace[1853515350] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16768; }","duration":"344.424725ms","start":"2026-08-20T01:48:18.000857Z","end":"2026-08-20T01:48:18.345281Z","steps":["trace[1853515350] 'agreement among raft nodes before linearized reading' (duration: 344.347893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.345302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.74366ms","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-20T01:48:18.345311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:18.000833Z","time spent":"344.473997ms","remote":"127.0.0.1:53754","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":"info","ts":"2026-08-20T01:48:18.345333Z","caller":"traceutil/trace.go:171","msg":"trace[1018658087] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:16768; }","duration":"346.782072ms","start":"2026-08-20T01:48:17.99854Z","end":"2026-08-20T01:48:18.345322Z","steps":["trace[1018658087] 'agreement among raft nodes before linearized reading' (duration: 346.696079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.345386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998534Z","time spent":"346.836103ms","remote":"127.0.0.1:53846","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":"warn","ts":"2026-08-20T01:48:18.345445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.587786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T01:48:18.345578Z","caller":"traceutil/trace.go:171","msg":"trace[644859056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16768; }","duration":"346.728219ms","start":"2026-08-20T01:48:17.998841Z","end":"2026-08-20T01:48:18.345569Z","steps":["trace[644859056] 'agreement among raft nodes before linearized reading' (duration: 346.578665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.345599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998835Z","time spent":"346.75805ms","remote":"127.0.0.1:53572","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-20T01:48:18.345629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.08205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T01:48:18.345661Z","caller":"traceutil/trace.go:171","msg":"trace[1336732169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16768; }","duration":"347.131821ms","start":"2026-08-20T01:48:17.998518Z","end":"2026-08-20T01:48:18.34565Z","steps":["trace[1336732169] 'agreement among raft nodes before linearized reading' (duration: 347.042888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.345691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.99851Z","time spent":"347.173733ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-20T01:48:18.345758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.165932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-20T01:48:18.34578Z","caller":"traceutil/trace.go:171","msg":"trace[2095444709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16768; }","duration":"347.192333ms","start":"2026-08-20T01:48:17.99858Z","end":"2026-08-20T01:48:18.345772Z","steps":["trace[2095444709] 'agreement among raft nodes before linearized reading' (duration: 347.112871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.345796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.998576Z","time spent":"347.216574ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-20T01:48:18.345849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.480464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:93 size:927089"} {"level":"info","ts":"2026-08-20T01:48:18.345908Z","caller":"traceutil/trace.go:171","msg":"trace[1618241992] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:93; response_revision:16768; }","duration":"341.573837ms","start":"2026-08-20T01:48:18.004319Z","end":"2026-08-20T01:48:18.345893Z","steps":["trace[1618241992] 'agreement among raft nodes before linearized reading' (duration: 340.350492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.345954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:18.004308Z","time spent":"341.631718ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":93,"response size":927112,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-20T01:48:18.346128Z","caller":"traceutil/trace.go:171","msg":"trace[648971653] transaction","detail":"{read_only:false; response_revision:16768; number_of_response:1; }","duration":"344.707474ms","start":"2026-08-20T01:48:18.001412Z","end":"2026-08-20T01:48:18.346119Z","steps":["trace[648971653] 'process raft request' (duration: 343.13877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.346198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:18.001395Z","time spent":"344.755885ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:48:18.346348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.417795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-08-20T01:48:18.346386Z","caller":"traceutil/trace.go:171","msg":"trace[1502327571] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16768; }","duration":"206.481896ms","start":"2026-08-20T01:48:18.139894Z","end":"2026-08-20T01:48:18.346376Z","steps":["trace[1502327571] 'agreement among raft nodes before linearized reading' (duration: 206.366183ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:48:56.815647Z","caller":"traceutil/trace.go:171","msg":"trace[1464512304] transaction","detail":"{read_only:false; response_revision:17088; number_of_response:1; }","duration":"106.396831ms","start":"2026-08-20T01:48:56.709221Z","end":"2026-08-20T01:48:56.815617Z","steps":["trace[1464512304] 'process raft request' (duration: 106.191975ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.033613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.455082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-20T01:49:02.033846Z","caller":"traceutil/trace.go:171","msg":"trace[1865848953] transaction","detail":"{read_only:false; response_revision:17118; number_of_response:1; }","duration":"267.150354ms","start":"2026-08-20T01:49:01.766675Z","end":"2026-08-20T01:49:02.033826Z","steps":["trace[1865848953] 'process raft request' (duration: 267.097583ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.033909Z","caller":"traceutil/trace.go:171","msg":"trace[848084622] transaction","detail":"{read_only:false; response_revision:17117; number_of_response:1; }","duration":"267.682419ms","start":"2026-08-20T01:49:01.76621Z","end":"2026-08-20T01:49:02.033892Z","steps":["trace[848084622] 'process raft request' (duration: 267.507604ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.034038Z","caller":"traceutil/trace.go:171","msg":"trace[445359820] transaction","detail":"{read_only:false; response_revision:17116; number_of_response:1; }","duration":"268.800611ms","start":"2026-08-20T01:49:01.765139Z","end":"2026-08-20T01:49:02.03394Z","steps":["trace[445359820] 'process raft request' (duration: 55.947057ms)","trace[445359820] 'compare' (duration: 212.206996ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:49:02.082541Z","caller":"traceutil/trace.go:171","msg":"trace[492330964] linearizableReadLoop","detail":"{readStateIndex:17700; appliedIndex:17699; }","duration":"128.536307ms","start":"2026-08-20T01:49:01.953984Z","end":"2026-08-20T01:49:02.08252Z","steps":["trace[492330964] 'read index received' (duration: 128.28184ms)","trace[492330964] 'applied index is now lower than readState.Index' (duration: 253.837µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:49:02.082733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.724152ms","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-20T01:49:02.082826Z","caller":"traceutil/trace.go:171","msg":"trace[933940104] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:17119; }","duration":"128.886027ms","start":"2026-08-20T01:49:01.953918Z","end":"2026-08-20T01:49:02.082804Z","steps":["trace[933940104] 'agreement among raft nodes before linearized reading' (duration: 128.667541ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.083188Z","caller":"traceutil/trace.go:171","msg":"trace[454777593] transaction","detail":"{read_only:false; response_revision:17119; number_of_response:1; }","duration":"293.331043ms","start":"2026-08-20T01:49:01.78983Z","end":"2026-08-20T01:49:02.083161Z","steps":["trace[454777593] 'process raft request' (duration: 292.549112ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.401038Z","caller":"traceutil/trace.go:171","msg":"trace[777253357] transaction","detail":"{read_only:false; response_revision:17124; number_of_response:1; }","duration":"303.092865ms","start":"2026-08-20T01:49:02.09792Z","end":"2026-08-20T01:49:02.401013Z","steps":["trace[777253357] 'process raft request' (duration: 303.041114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.401177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:49:02.097889Z","time spent":"303.228499ms","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:49:02.401447Z","caller":"traceutil/trace.go:171","msg":"trace[2051788833] transaction","detail":"{read_only:false; response_revision:17122; number_of_response:1; }","duration":"304.464013ms","start":"2026-08-20T01:49:02.096969Z","end":"2026-08-20T01:49:02.401433Z","steps":["trace[2051788833] 'process raft request' (duration: 265.477978ms)","trace[2051788833] 'compare' (duration: 37.412961ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:49:02.401505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:49:02.096938Z","time spent":"304.544235ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":658,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:49:02.401707Z","caller":"traceutil/trace.go:171","msg":"trace[628085004] transaction","detail":"{read_only:false; response_revision:17123; number_of_response:1; }","duration":"304.34375ms","start":"2026-08-20T01:49:02.097354Z","end":"2026-08-20T01:49:02.401697Z","steps":["trace[628085004] 'process raft request' (duration: 303.520277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.40178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:49:02.097346Z","time spent":"304.407552ms","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:49:02.670372Z","caller":"traceutil/trace.go:171","msg":"trace[412965802] linearizableReadLoop","detail":"{readStateIndex:17706; appliedIndex:17705; }","duration":"261.657302ms","start":"2026-08-20T01:49:02.408686Z","end":"2026-08-20T01:49:02.670343Z","steps":["trace[412965802] 'read index received' (duration: 256.974931ms)","trace[412965802] 'applied index is now lower than readState.Index' (duration: 4.681351ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:49:02.670707Z","caller":"traceutil/trace.go:171","msg":"trace[466222314] transaction","detail":"{read_only:false; response_revision:17125; number_of_response:1; }","duration":"263.650647ms","start":"2026-08-20T01:49:02.406889Z","end":"2026-08-20T01:49:02.67054Z","steps":["trace[466222314] 'process raft request' (duration: 258.671698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.670964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.255379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-08-20T01:49:02.671037Z","caller":"traceutil/trace.go:171","msg":"trace[2012064546] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:17125; }","duration":"262.389003ms","start":"2026-08-20T01:49:02.408632Z","end":"2026-08-20T01:49:02.671021Z","steps":["trace[2012064546] 'agreement among raft nodes before linearized reading' (duration: 261.94658ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.766354Z","caller":"traceutil/trace.go:171","msg":"trace[1346213997] transaction","detail":"{read_only:false; response_revision:17127; number_of_response:1; }","duration":"266.129676ms","start":"2026-08-20T01:49:02.500176Z","end":"2026-08-20T01:49:02.766306Z","steps":["trace[1346213997] 'process raft request' (duration: 265.971522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.766567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.541846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-20T01:49:02.766459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.410197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"warn","ts":"2026-08-20T01:49:02.76671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.565384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-20T01:49:02.766744Z","caller":"traceutil/trace.go:171","msg":"trace[1654041646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17128; }","duration":"194.624656ms","start":"2026-08-20T01:49:02.572113Z","end":"2026-08-20T01:49:02.766737Z","steps":["trace[1654041646] 'agreement among raft nodes before linearized reading' (duration: 194.545744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.766739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.186994ms","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-20T01:49:02.76682Z","caller":"traceutil/trace.go:171","msg":"trace[1760096328] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:17128; }","duration":"162.284387ms","start":"2026-08-20T01:49:02.604509Z","end":"2026-08-20T01:49:02.766793Z","steps":["trace[1760096328] 'agreement among raft nodes before linearized reading' (duration: 162.138903ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.766748Z","caller":"traceutil/trace.go:171","msg":"trace[2001343715] transaction","detail":"{read_only:false; response_revision:17128; number_of_response:1; }","duration":"263.903854ms","start":"2026-08-20T01:49:02.502728Z","end":"2026-08-20T01:49:02.766632Z","steps":["trace[2001343715] 'process raft request' (duration: 263.476162ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.766719Z","caller":"traceutil/trace.go:171","msg":"trace[1677208148] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:17128; }","duration":"357.704115ms","start":"2026-08-20T01:49:02.408976Z","end":"2026-08-20T01:49:02.766681Z","steps":["trace[1677208148] 'agreement among raft nodes before linearized reading' (duration: 357.310414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.767042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:49:02.408958Z","time spent":"358.036224ms","remote":"127.0.0.1:53976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-08-20T01:49:02.766617Z","caller":"traceutil/trace.go:171","msg":"trace[767661313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17128; }","duration":"193.602307ms","start":"2026-08-20T01:49:02.572998Z","end":"2026-08-20T01:49:02.7666Z","steps":["trace[767661313] 'agreement among raft nodes before linearized reading' (duration: 193.508925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.766582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.633676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-20T01:49:02.767148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.532058ms","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-20T01:49:02.767227Z","caller":"traceutil/trace.go:171","msg":"trace[995764805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17128; }","duration":"197.662441ms","start":"2026-08-20T01:49:02.569555Z","end":"2026-08-20T01:49:02.767218Z","steps":["trace[995764805] 'agreement among raft nodes before linearized reading' (duration: 197.240339ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.767299Z","caller":"traceutil/trace.go:171","msg":"trace[782063843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17128; }","duration":"191.355185ms","start":"2026-08-20T01:49:02.575853Z","end":"2026-08-20T01:49:02.767208Z","steps":["trace[782063843] 'agreement among raft nodes before linearized reading' (duration: 190.493971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.767296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.903417ms","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-20T01:49:02.766306Z","caller":"traceutil/trace.go:171","msg":"trace[1637718819] transaction","detail":"{read_only:false; response_revision:17126; number_of_response:1; }","duration":"357.484739ms","start":"2026-08-20T01:49:02.40877Z","end":"2026-08-20T01:49:02.766255Z","steps":["trace[1637718819] 'process raft request' (duration: 357.231672ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.767475Z","caller":"traceutil/trace.go:171","msg":"trace[1371037497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17128; }","duration":"212.132353ms","start":"2026-08-20T01:49:02.555291Z","end":"2026-08-20T01:49:02.767424Z","steps":["trace[1371037497] 'agreement among raft nodes before linearized reading' (duration: 211.522446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:02.767652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:49:02.408762Z","time spent":"358.787475ms","remote":"127.0.0.1:53652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-20T01:49:02.768033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.324155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T01:49:02.768122Z","caller":"traceutil/trace.go:171","msg":"trace[1317231823] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17128; }","duration":"163.472489ms","start":"2026-08-20T01:49:02.604626Z","end":"2026-08-20T01:49:02.768098Z","steps":["trace[1317231823] 'agreement among raft nodes before linearized reading' (duration: 161.830623ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:02.81919Z","caller":"traceutil/trace.go:171","msg":"trace[1180664739] transaction","detail":"{read_only:false; response_revision:17129; number_of_response:1; }","duration":"144.195443ms","start":"2026-08-20T01:49:02.674971Z","end":"2026-08-20T01:49:02.819167Z","steps":["trace[1180664739] 'process raft request' (duration: 105.898668ms)","trace[1180664739] 'compare' (duration: 38.148581ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:49:39.198222Z","caller":"traceutil/trace.go:171","msg":"trace[1546640506] transaction","detail":"{read_only:false; response_revision:17624; number_of_response:1; }","duration":"139.648566ms","start":"2026-08-20T01:49:39.058545Z","end":"2026-08-20T01:49:39.198194Z","steps":["trace[1546640506] 'process raft request' (duration: 139.502502ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:39.198935Z","caller":"traceutil/trace.go:171","msg":"trace[1280301352] linearizableReadLoop","detail":"{readStateIndex:18221; appliedIndex:18219; }","duration":"137.047174ms","start":"2026-08-20T01:49:39.061862Z","end":"2026-08-20T01:49:39.19891Z","steps":["trace[1280301352] 'read index received' (duration: 136.15575ms)","trace[1280301352] 'applied index is now lower than readState.Index' (duration: 890.044µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:49:39.199227Z","caller":"traceutil/trace.go:171","msg":"trace[311797253] transaction","detail":"{read_only:false; response_revision:17625; number_of_response:1; }","duration":"138.207447ms","start":"2026-08-20T01:49:39.060999Z","end":"2026-08-20T01:49:39.199206Z","steps":["trace[311797253] 'process raft request' (duration: 137.777705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:39.199412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.502987ms","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-20T01:49:39.19948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.362477ms","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-20T01:49:39.199507Z","caller":"traceutil/trace.go:171","msg":"trace[776476407] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17625; }","duration":"114.61868ms","start":"2026-08-20T01:49:39.084868Z","end":"2026-08-20T01:49:39.199487Z","steps":["trace[776476407] 'agreement among raft nodes before linearized reading' (duration: 114.420615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:39.1995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.736107ms","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-20T01:49:39.199544Z","caller":"traceutil/trace.go:171","msg":"trace[505975530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17625; }","duration":"117.452289ms","start":"2026-08-20T01:49:39.082075Z","end":"2026-08-20T01:49:39.199527Z","steps":["trace[505975530] 'agreement among raft nodes before linearized reading' (duration: 117.280094ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:39.199568Z","caller":"traceutil/trace.go:171","msg":"trace[2051033790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17625; }","duration":"117.838639ms","start":"2026-08-20T01:49:39.081713Z","end":"2026-08-20T01:49:39.199551Z","steps":["trace[2051033790] 'agreement among raft nodes before linearized reading' (duration: 117.664254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:39.199501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.62994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T01:49:39.199641Z","caller":"traceutil/trace.go:171","msg":"trace[350649090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17625; }","duration":"137.798745ms","start":"2026-08-20T01:49:39.061826Z","end":"2026-08-20T01:49:39.199624Z","steps":["trace[350649090] 'agreement among raft nodes before linearized reading' (duration: 137.576759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:39.200016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.883778ms","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-20T01:49:39.200299Z","caller":"traceutil/trace.go:171","msg":"trace[527185012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17625; }","duration":"138.195826ms","start":"2026-08-20T01:49:39.062091Z","end":"2026-08-20T01:49:39.200287Z","steps":["trace[527185012] 'agreement among raft nodes before linearized reading' (duration: 137.115566ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:50:00.271385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15210} {"level":"info","ts":"2026-08-20T01:50:00.368244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15210,"took":"93.77446ms","hash":190901178,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":32997376,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-20T01:50:00.368305Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":190901178,"revision":15210,"compact-revision":12849} {"level":"info","ts":"2026-08-20T01:52:56.155778Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T01:52:56.161592Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-20T01:52:56.161703Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-20T01:55:00.278001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17800} {"level":"info","ts":"2026-08-20T01:55:00.389437Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17800,"took":"107.81508ms","hash":2602379060,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":31412224,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-20T01:55:00.38951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2602379060,"revision":17800,"compact-revision":15210} {"level":"info","ts":"2026-08-20T01:56:34.593471Z","caller":"traceutil/trace.go:171","msg":"trace[51228187] transaction","detail":"{read_only:false; response_revision:20880; number_of_response:1; }","duration":"178.468745ms","start":"2026-08-20T01:56:34.414935Z","end":"2026-08-20T01:56:34.593403Z","steps":["trace[51228187] 'process raft request' (duration: 146.588558ms)","trace[51228187] 'compare' (duration: 31.390763ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:56:34.626989Z","caller":"traceutil/trace.go:171","msg":"trace[1242555148] transaction","detail":"{read_only:false; response_revision:20881; number_of_response:1; }","duration":"112.714245ms","start":"2026-08-20T01:56:34.514248Z","end":"2026-08-20T01:56:34.626962Z","steps":["trace[1242555148] 'process raft request' (duration: 112.55673ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:56:34.627317Z","caller":"traceutil/trace.go:171","msg":"trace[897705609] transaction","detail":"{read_only:false; response_revision:20882; number_of_response:1; }","duration":"110.810422ms","start":"2026-08-20T01:56:34.516461Z","end":"2026-08-20T01:56:34.627271Z","steps":["trace[897705609] 'process raft request' (duration: 110.453323ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:56:34.956851Z","caller":"traceutil/trace.go:171","msg":"trace[1307441008] transaction","detail":"{read_only:false; response_revision:20883; number_of_response:1; }","duration":"156.570835ms","start":"2026-08-20T01:56:34.800246Z","end":"2026-08-20T01:56:34.956817Z","steps":["trace[1307441008] 'process raft request' (duration: 156.294367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:56:35.684948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.257225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T01:56:35.685168Z","caller":"traceutil/trace.go:171","msg":"trace[1899083688] linearizableReadLoop","detail":"{readStateIndex:21585; appliedIndex:21583; }","duration":"232.438715ms","start":"2026-08-20T01:56:35.452709Z","end":"2026-08-20T01:56:35.685148Z","steps":["trace[1899083688] 'read index received' (duration: 102.881092ms)","trace[1899083688] 'applied index is now lower than readState.Index' (duration: 129.556023ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:56:35.685265Z","caller":"traceutil/trace.go:171","msg":"trace[1706955655] transaction","detail":"{read_only:false; response_revision:20888; number_of_response:1; }","duration":"262.44463ms","start":"2026-08-20T01:56:35.422809Z","end":"2026-08-20T01:56:35.685253Z","steps":["trace[1706955655] 'process raft request' (duration: 262.256505ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:56:35.685311Z","caller":"traceutil/trace.go:171","msg":"trace[1518177949] transaction","detail":"{read_only:false; response_revision:20887; number_of_response:1; }","duration":"262.474171ms","start":"2026-08-20T01:56:35.422796Z","end":"2026-08-20T01:56:35.68527Z","steps":["trace[1518177949] 'process raft request' (duration: 132.800454ms)","trace[1518177949] 'compare' (duration: 128.824903ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:56:35.685572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.854347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-20T01:56:35.685633Z","caller":"traceutil/trace.go:171","msg":"trace[2085159101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20888; }","duration":"232.955939ms","start":"2026-08-20T01:56:35.452649Z","end":"2026-08-20T01:56:35.685604Z","steps":["trace[2085159101] 'agreement among raft nodes before linearized reading' (duration: 232.813965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:56:35.685834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.745417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T01:56:35.685932Z","caller":"traceutil/trace.go:171","msg":"trace[855086477] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20888; }","duration":"212.923932ms","start":"2026-08-20T01:56:35.472983Z","end":"2026-08-20T01:56:35.685907Z","steps":["trace[855086477] 'agreement among raft nodes before linearized reading' (duration: 212.651405ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:56:35.858205Z","caller":"traceutil/trace.go:171","msg":"trace[1968869421] transaction","detail":"{read_only:false; response_revision:20889; number_of_response:1; }","duration":"168.27109ms","start":"2026-08-20T01:56:35.689903Z","end":"2026-08-20T01:56:35.858174Z","steps":["trace[1968869421] 'process raft request' (duration: 155.589207ms)","trace[1968869421] 'compare' (duration: 12.363874ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:56:35.894776Z","caller":"traceutil/trace.go:171","msg":"trace[152822295] linearizableReadLoop","detail":"{readStateIndex:21587; appliedIndex:21585; }","duration":"198.720458ms","start":"2026-08-20T01:56:35.69603Z","end":"2026-08-20T01:56:35.89475Z","steps":["trace[152822295] 'read index received' (duration: 149.57017ms)","trace[152822295] 'applied index is now lower than readState.Index' (duration: 49.148808ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:56:35.89495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.890622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T01:56:35.895002Z","caller":"traceutil/trace.go:171","msg":"trace[306638349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20890; }","duration":"199.015436ms","start":"2026-08-20T01:56:35.695972Z","end":"2026-08-20T01:56:35.894987Z","steps":["trace[306638349] 'agreement among raft nodes before linearized reading' (duration: 198.919814ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:56:35.894969Z","caller":"traceutil/trace.go:171","msg":"trace[713975805] transaction","detail":"{read_only:false; response_revision:20890; number_of_response:1; }","duration":"204.196929ms","start":"2026-08-20T01:56:35.69072Z","end":"2026-08-20T01:56:35.894917Z","steps":["trace[713975805] 'process raft request' (duration: 203.900511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:56:35.89513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.625977ms","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":"info","ts":"2026-08-20T01:56:35.895189Z","caller":"traceutil/trace.go:171","msg":"trace[1060224723] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:20890; }","duration":"188.705119ms","start":"2026-08-20T01:56:35.706467Z","end":"2026-08-20T01:56:35.895172Z","steps":["trace[1060224723] 'agreement among raft nodes before linearized reading' (duration: 188.553715ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:00:00.283823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20232} {"level":"info","ts":"2026-08-20T02:00:00.392773Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20232,"took":"105.461673ms","hash":1423620713,"current-db-size-bytes":56700928,"current-db-size":"57 MB","current-db-size-in-use-bytes":31510528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T02:00:00.392825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1423620713,"revision":20232,"compact-revision":17800} {"level":"info","ts":"2026-08-20T02:00:07.23036Z","caller":"traceutil/trace.go:171","msg":"trace[548700796] linearizableReadLoop","detail":"{readStateIndex:23500; appliedIndex:23499; }","duration":"116.215243ms","start":"2026-08-20T02:00:07.114121Z","end":"2026-08-20T02:00:07.230336Z","steps":["trace[548700796] 'read index received' (duration: 116.012667ms)","trace[548700796] 'applied index is now lower than readState.Index' (duration: 201.956µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:00:07.230719Z","caller":"traceutil/trace.go:171","msg":"trace[1651417913] transaction","detail":"{read_only:false; response_revision:22743; number_of_response:1; }","duration":"116.608264ms","start":"2026-08-20T02:00:07.114098Z","end":"2026-08-20T02:00:07.230706Z","steps":["trace[1651417913] 'process raft request' (duration: 116.10711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:00:07.230931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.738097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:00:07.231047Z","caller":"traceutil/trace.go:171","msg":"trace[592227582] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:22743; }","duration":"116.884502ms","start":"2026-08-20T02:00:07.114137Z","end":"2026-08-20T02:00:07.231022Z","steps":["trace[592227582] 'agreement among raft nodes before linearized reading' (duration: 116.602344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:00:07.231191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.058656ms","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":"info","ts":"2026-08-20T02:00:07.231271Z","caller":"traceutil/trace.go:171","msg":"trace[1183358513] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:22743; }","duration":"117.17257ms","start":"2026-08-20T02:00:07.114085Z","end":"2026-08-20T02:00:07.231257Z","steps":["trace[1183358513] 'agreement among raft nodes before linearized reading' (duration: 117.069147ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:00:07.2316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.554285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/orc-system/orc-controller-manager-b56d5d966-cxmjb\" ","response":"range_response_count:1 size:3861"} {"level":"info","ts":"2026-08-20T02:00:07.231669Z","caller":"traceutil/trace.go:171","msg":"trace[1971275648] range","detail":"{range_begin:/registry/pods/orc-system/orc-controller-manager-b56d5d966-cxmjb; range_end:; response_count:1; response_revision:22743; }","duration":"115.647178ms","start":"2026-08-20T02:00:07.116004Z","end":"2026-08-20T02:00:07.231651Z","steps":["trace[1971275648] 'agreement among raft nodes before linearized reading' (duration: 115.471333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:00:14.603033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.357084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:00:14.603145Z","caller":"traceutil/trace.go:171","msg":"trace[727036809] linearizableReadLoop","detail":"{readStateIndex:24011; appliedIndex:24009; }","duration":"200.985266ms","start":"2026-08-20T02:00:14.402143Z","end":"2026-08-20T02:00:14.603128Z","steps":["trace[727036809] 'read index received' (duration: 74.825403ms)","trace[727036809] 'applied index is now lower than readState.Index' (duration: 126.158593ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:00:14.603164Z","caller":"traceutil/trace.go:171","msg":"trace[66497962] transaction","detail":"{read_only:false; response_revision:23244; number_of_response:1; }","duration":"201.648455ms","start":"2026-08-20T02:00:14.401502Z","end":"2026-08-20T02:00:14.603151Z","steps":["trace[66497962] 'process raft request' (duration: 87.055494ms)","trace[66497962] 'compare' (duration: 114.183789ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:00:14.60334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.179532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager-6f6766ffcc-njq4z\" ","response":"range_response_count:1 size:5571"} {"level":"info","ts":"2026-08-20T02:00:14.603423Z","caller":"traceutil/trace.go:171","msg":"trace[1453219458] range","detail":"{range_begin:/registry/pods/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager-6f6766ffcc-njq4z; range_end:; response_count:1; response_revision:23244; }","duration":"201.317176ms","start":"2026-08-20T02:00:14.402092Z","end":"2026-08-20T02:00:14.603409Z","steps":["trace[1453219458] 'agreement among raft nodes before linearized reading' (duration: 201.10012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:00:14.64675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.246591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-20T02:00:14.646789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.052622ms","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-20T02:00:14.646847Z","caller":"traceutil/trace.go:171","msg":"trace[5452498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23245; }","duration":"142.394335ms","start":"2026-08-20T02:00:14.504413Z","end":"2026-08-20T02:00:14.646807Z","steps":["trace[5452498] 'agreement among raft nodes before linearized reading' (duration: 142.094137ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:00:14.646886Z","caller":"traceutil/trace.go:171","msg":"trace[1436065753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23245; }","duration":"139.210307ms","start":"2026-08-20T02:00:14.507657Z","end":"2026-08-20T02:00:14.646867Z","steps":["trace[1436065753] 'agreement among raft nodes before linearized reading' (duration: 138.915699ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:25.072211Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006598.wal"} {"level":"info","ts":"2026-08-20T02:03:37.874343Z","caller":"traceutil/trace.go:171","msg":"trace[2046714866] linearizableReadLoop","detail":"{readStateIndex:26192; appliedIndex:26191; }","duration":"179.046676ms","start":"2026-08-20T02:03:37.695262Z","end":"2026-08-20T02:03:37.874309Z","steps":["trace[2046714866] 'read index received' (duration: 178.759108ms)","trace[2046714866] 'applied index is now lower than readState.Index' (duration: 285.898µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:37.874531Z","caller":"traceutil/trace.go:171","msg":"trace[1498484604] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"280.727927ms","start":"2026-08-20T02:03:37.59379Z","end":"2026-08-20T02:03:37.874518Z","steps":["trace[1498484604] 'process raft request' (duration: 280.299135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:37.87479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.51424ms","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-20T02:03:37.874882Z","caller":"traceutil/trace.go:171","msg":"trace[847454320] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25374; }","duration":"127.638164ms","start":"2026-08-20T02:03:37.747222Z","end":"2026-08-20T02:03:37.87486Z","steps":["trace[847454320] 'agreement among raft nodes before linearized reading' (duration: 127.398257ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:37.875185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.602932ms","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-20T02:03:37.875223Z","caller":"traceutil/trace.go:171","msg":"trace[397394481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25374; }","duration":"114.697684ms","start":"2026-08-20T02:03:37.760516Z","end":"2026-08-20T02:03:37.875214Z","steps":["trace[397394481] 'agreement among raft nodes before linearized reading' (duration: 114.598541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:37.875403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.109755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:37.875479Z","caller":"traceutil/trace.go:171","msg":"trace[137759166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25374; }","duration":"180.229289ms","start":"2026-08-20T02:03:37.69521Z","end":"2026-08-20T02:03:37.87544Z","steps":["trace[137759166] 'agreement among raft nodes before linearized reading' (duration: 180.126856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.527438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.128542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:38.527567Z","caller":"traceutil/trace.go:171","msg":"trace[1558556301] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"646.888904ms","start":"2026-08-20T02:03:37.880653Z","end":"2026-08-20T02:03:38.527542Z","steps":["trace[1558556301] 'process raft request' (duration: 320.549956ms)","trace[1558556301] 'compare' (duration: 325.816013ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:38.527643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.88063Z","time spent":"646.977177ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:38.526448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.838006ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:38.529651Z","caller":"traceutil/trace.go:171","msg":"trace[1456266525] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25374; }","duration":"550.971253ms","start":"2026-08-20T02:03:37.978576Z","end":"2026-08-20T02:03:38.529547Z","steps":["trace[1456266525] 'range keys from in-memory index tree' (duration: 547.818745ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.53884Z","caller":"traceutil/trace.go:171","msg":"trace[1425064470] linearizableReadLoop","detail":"{readStateIndex:26194; appliedIndex:26192; }","duration":"654.940397ms","start":"2026-08-20T02:03:37.883863Z","end":"2026-08-20T02:03:38.538803Z","steps":["trace[1425064470] 'read index received' (duration: 317.331536ms)","trace[1425064470] 'applied index is now lower than readState.Index' (duration: 337.607481ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:38.539172Z","caller":"traceutil/trace.go:171","msg":"trace[1299584483] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"656.266884ms","start":"2026-08-20T02:03:37.882894Z","end":"2026-08-20T02:03:38.539161Z","steps":["trace[1299584483] 'process raft request' (duration: 655.613026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.539322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.882871Z","time spent":"656.326286ms","remote":"127.0.0.1:53846","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-20T02:03:38.539307Z","caller":"traceutil/trace.go:171","msg":"trace[66679599] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"345.523511ms","start":"2026-08-20T02:03:38.193719Z","end":"2026-08-20T02:03:38.539243Z","steps":["trace[66679599] 'process raft request' (duration: 345.040328ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.539419Z","caller":"traceutil/trace.go:171","msg":"trace[279743662] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"654.466915ms","start":"2026-08-20T02:03:37.884923Z","end":"2026-08-20T02:03:38.53939Z","steps":["trace[279743662] 'process raft request' (duration: 653.805306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.539447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.565455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-08-20T02:03:38.539484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.884909Z","time spent":"654.544536ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:38.539506Z","caller":"traceutil/trace.go:171","msg":"trace[811658105] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25378; }","duration":"655.635797ms","start":"2026-08-20T02:03:37.883855Z","end":"2026-08-20T02:03:38.539491Z","steps":["trace[811658105] 'agreement among raft nodes before linearized reading' (duration: 655.406141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.539508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.193694Z","time spent":"345.712517ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:38.539542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.883831Z","time spent":"655.703839ms","remote":"127.0.0.1:53754","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":"info","ts":"2026-08-20T02:03:38.692179Z","caller":"traceutil/trace.go:171","msg":"trace[851753573] linearizableReadLoop","detail":"{readStateIndex:26198; appliedIndex:26196; }","duration":"153.265398ms","start":"2026-08-20T02:03:38.538896Z","end":"2026-08-20T02:03:38.692161Z","steps":["trace[851753573] 'read index received' (duration: 139.423972ms)","trace[851753573] 'applied index is now lower than readState.Index' (duration: 13.840306ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:38.692346Z","caller":"traceutil/trace.go:171","msg":"trace[102549786] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"468.391193ms","start":"2026-08-20T02:03:38.223944Z","end":"2026-08-20T02:03:38.692336Z","steps":["trace[102549786] 'process raft request' (duration: 454.218458ms)","trace[102549786] 'compare' (duration: 13.675571ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:38.692422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.223926Z","time spent":"468.441785ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:38.69254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.145043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:03:38.69259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.167874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T02:03:38.692608Z","caller":"traceutil/trace.go:171","msg":"trace[164212546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25380; }","duration":"530.227195ms","start":"2026-08-20T02:03:38.162375Z","end":"2026-08-20T02:03:38.692603Z","steps":["trace[164212546] 'agreement among raft nodes before linearized reading' (duration: 530.152743ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.692613Z","caller":"traceutil/trace.go:171","msg":"trace[250871790] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25380; }","duration":"502.285857ms","start":"2026-08-20T02:03:38.190307Z","end":"2026-08-20T02:03:38.692593Z","steps":["trace[250871790] 'agreement among raft nodes before linearized reading' (duration: 502.131443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.692657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.190258Z","time spent":"502.388869ms","remote":"127.0.0.1:57384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:03:38.692681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.725211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:38.692695Z","caller":"traceutil/trace.go:171","msg":"trace[1147321588] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25380; }","duration":"589.757863ms","start":"2026-08-20T02:03:38.102933Z","end":"2026-08-20T02:03:38.692691Z","steps":["trace[1147321588] 'agreement among raft nodes before linearized reading' (duration: 589.698801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.692707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.102924Z","time spent":"589.780323ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:03:38.692818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.072559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:38.69283Z","caller":"traceutil/trace.go:171","msg":"trace[358560191] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25380; }","duration":"591.12681ms","start":"2026-08-20T02:03:38.1017Z","end":"2026-08-20T02:03:38.692826Z","steps":["trace[358560191] 'agreement among raft nodes before linearized reading' (duration: 591.087499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.692842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.101675Z","time spent":"591.163862ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:03:38.692928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.470178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:38.69294Z","caller":"traceutil/trace.go:171","msg":"trace[751964775] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25380; }","duration":"620.482508ms","start":"2026-08-20T02:03:38.072454Z","end":"2026-08-20T02:03:38.692936Z","steps":["trace[751964775] 'agreement among raft nodes before linearized reading' (duration: 620.446267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.692952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.072394Z","time spent":"620.5555ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:03:38.693014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.401946ms","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":"warn","ts":"2026-08-20T02:03:38.693028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.175749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-20T02:03:38.693007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.236884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:38.693057Z","caller":"traceutil/trace.go:171","msg":"trace[1111104531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25380; }","duration":"732.236391ms","start":"2026-08-20T02:03:37.960816Z","end":"2026-08-20T02:03:38.693052Z","steps":["trace[1111104531] 'agreement among raft nodes before linearized reading' (duration: 732.183399ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.693055Z","caller":"traceutil/trace.go:171","msg":"trace[936148719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25380; }","duration":"485.440317ms","start":"2026-08-20T02:03:38.2076Z","end":"2026-08-20T02:03:38.693041Z","steps":["trace[936148719] 'agreement among raft nodes before linearized reading' (duration: 485.291613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.693071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.960794Z","time spent":"732.274811ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-20T02:03:38.693087Z","caller":"traceutil/trace.go:171","msg":"trace[1911899836] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25380; }","duration":"433.371598ms","start":"2026-08-20T02:03:38.259694Z","end":"2026-08-20T02:03:38.693065Z","steps":["trace[1911899836] 'agreement among raft nodes before linearized reading' (duration: 433.202943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.693103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.207551Z","time spent":"485.52869ms","remote":"127.0.0.1:53846","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-20T02:03:38.693123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.259671Z","time spent":"433.44421ms","remote":"127.0.0.1:45456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:03:38.692624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.162351Z","time spent":"530.267976ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-20T02:03:38.693366Z","caller":"traceutil/trace.go:171","msg":"trace[724594599] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"455.732171ms","start":"2026-08-20T02:03:38.237621Z","end":"2026-08-20T02:03:38.693353Z","steps":["trace[724594599] 'process raft request' (duration: 454.503287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.693457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.2376Z","time spent":"455.791512ms","remote":"127.0.0.1:53846","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-20T02:03:38.69347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.174849ms","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-20T02:03:38.693525Z","caller":"traceutil/trace.go:171","msg":"trace[324906981] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25380; }","duration":"430.274421ms","start":"2026-08-20T02:03:38.263237Z","end":"2026-08-20T02:03:38.693511Z","steps":["trace[324906981] 'agreement among raft nodes before linearized reading' (duration: 430.157268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.69356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.26322Z","time spent":"430.331204ms","remote":"127.0.0.1:53864","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-20T02:03:38.693611Z","caller":"traceutil/trace.go:171","msg":"trace[1686361723] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"118.908691ms","start":"2026-08-20T02:03:38.57469Z","end":"2026-08-20T02:03:38.693599Z","steps":["trace[1686361723] 'process raft request' (duration: 118.791478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.693627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.71179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T02:03:38.693665Z","caller":"traceutil/trace.go:171","msg":"trace[1960015421] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25380; }","duration":"227.792473ms","start":"2026-08-20T02:03:38.465861Z","end":"2026-08-20T02:03:38.693654Z","steps":["trace[1960015421] 'agreement among raft nodes before linearized reading' (duration: 227.65578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.945554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.660571ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-20T02:03:38.945787Z","caller":"traceutil/trace.go:171","msg":"trace[224145688] linearizableReadLoop","detail":"{readStateIndex:26200; appliedIndex:26198; }","duration":"253.54571ms","start":"2026-08-20T02:03:38.692225Z","end":"2026-08-20T02:03:38.945771Z","steps":["trace[224145688] 'read index received' (duration: 1.359117ms)","trace[224145688] 'applied index is now lower than readState.Index' (duration: 252.185493ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:38.945986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.894925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17940"} {"level":"info","ts":"2026-08-20T02:03:38.946054Z","caller":"traceutil/trace.go:171","msg":"trace[672308197] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25381; }","duration":"404.991338ms","start":"2026-08-20T02:03:38.541047Z","end":"2026-08-20T02:03:38.946038Z","steps":["trace[672308197] 'agreement among raft nodes before linearized reading' (duration: 404.777652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.946089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.541013Z","time spent":"405.06594ms","remote":"127.0.0.1:53752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":17963,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-20T02:03:38.94607Z","caller":"traceutil/trace.go:171","msg":"trace[437981937] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"248.786968ms","start":"2026-08-20T02:03:38.697262Z","end":"2026-08-20T02:03:38.946049Z","steps":["trace[437981937] 'process raft request' (duration: 248.390677ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.946548Z","caller":"traceutil/trace.go:171","msg":"trace[1256345357] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"248.716176ms","start":"2026-08-20T02:03:38.697777Z","end":"2026-08-20T02:03:38.946494Z","steps":["trace[1256345357] 'process raft request' (duration: 248.662285ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.946723Z","caller":"traceutil/trace.go:171","msg":"trace[136210937] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"249.155198ms","start":"2026-08-20T02:03:38.697554Z","end":"2026-08-20T02:03:38.946709Z","steps":["trace[136210937] 'process raft request' (duration: 248.424268ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.946797Z","caller":"traceutil/trace.go:171","msg":"trace[365286998] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"249.138438ms","start":"2026-08-20T02:03:38.69764Z","end":"2026-08-20T02:03:38.946778Z","steps":["trace[365286998] 'process raft request' (duration: 248.698356ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.954618Z","caller":"traceutil/trace.go:171","msg":"trace[1513791473] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"200.785361ms","start":"2026-08-20T02:03:38.753788Z","end":"2026-08-20T02:03:38.954574Z","steps":["trace[1513791473] 'process raft request' (duration: 200.630067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.954673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.435024ms","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-20T02:03:38.955003Z","caller":"traceutil/trace.go:171","msg":"trace[60380763] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25386; }","duration":"222.844636ms","start":"2026-08-20T02:03:38.732139Z","end":"2026-08-20T02:03:38.954983Z","steps":["trace[60380763] 'agreement among raft nodes before linearized reading' (duration: 222.407113ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:44.115881Z","caller":"traceutil/trace.go:171","msg":"trace[399524488] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"145.725718ms","start":"2026-08-20T02:03:43.970126Z","end":"2026-08-20T02:03:44.115852Z","steps":["trace[399524488] 'process raft request' (duration: 145.052899ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:44.681015Z","caller":"traceutil/trace.go:171","msg":"trace[1844503642] linearizableReadLoop","detail":"{readStateIndex:26247; appliedIndex:26245; }","duration":"116.841084ms","start":"2026-08-20T02:03:44.564154Z","end":"2026-08-20T02:03:44.680995Z","steps":["trace[1844503642] 'read index received' (duration: 82.118577ms)","trace[1844503642] 'applied index is now lower than readState.Index' (duration: 34.721797ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:44.681099Z","caller":"traceutil/trace.go:171","msg":"trace[1090216935] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"123.834228ms","start":"2026-08-20T02:03:44.557257Z","end":"2026-08-20T02:03:44.681091Z","steps":["trace[1090216935] 'process raft request' (duration: 123.634022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:44.681255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.088431ms","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-20T02:03:44.681281Z","caller":"traceutil/trace.go:171","msg":"trace[313899162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25427; }","duration":"117.135702ms","start":"2026-08-20T02:03:44.564135Z","end":"2026-08-20T02:03:44.681271Z","steps":["trace[313899162] 'agreement among raft nodes before linearized reading' (duration: 117.053029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:44.68136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.483003ms","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-20T02:03:44.681382Z","caller":"traceutil/trace.go:171","msg":"trace[997718563] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25427; }","duration":"116.513965ms","start":"2026-08-20T02:03:44.564861Z","end":"2026-08-20T02:03:44.681375Z","steps":["trace[997718563] 'agreement among raft nodes before linearized reading' (duration: 116.466413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:44.99048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.028186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:44.990812Z","caller":"traceutil/trace.go:171","msg":"trace[501407018] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"277.174018ms","start":"2026-08-20T02:03:44.713618Z","end":"2026-08-20T02:03:44.990792Z","steps":["trace[501407018] 'process raft request' (duration: 277.087556ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:44.990985Z","caller":"traceutil/trace.go:171","msg":"trace[791525829] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"304.087507ms","start":"2026-08-20T02:03:44.686884Z","end":"2026-08-20T02:03:44.990971Z","steps":["trace[791525829] 'process raft request' (duration: 303.720057ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:44.99095Z","caller":"traceutil/trace.go:171","msg":"trace[1612160092] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"304.689433ms","start":"2026-08-20T02:03:44.686216Z","end":"2026-08-20T02:03:44.990905Z","steps":["trace[1612160092] 'process raft request' (duration: 144.116142ms)","trace[1612160092] 'compare' (duration: 159.845851ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:44.991055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:44.686861Z","time spent":"304.155798ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:44.991142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:44.686188Z","time spent":"304.863178ms","remote":"127.0.0.1:53846","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-20T02:03:44.991277Z","caller":"traceutil/trace.go:171","msg":"trace[49288458] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"277.382204ms","start":"2026-08-20T02:03:44.713879Z","end":"2026-08-20T02:03:44.991261Z","steps":["trace[49288458] 'process raft request' (duration: 277.163418ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:44.991244Z","caller":"traceutil/trace.go:171","msg":"trace[987417099] linearizableReadLoop","detail":"{readStateIndex:26249; appliedIndex:26247; }","duration":"282.994131ms","start":"2026-08-20T02:03:44.708217Z","end":"2026-08-20T02:03:44.991211Z","steps":["trace[987417099] 'read index received' (duration: 122.102171ms)","trace[987417099] 'applied index is now lower than readState.Index' (duration: 160.89008ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:44.991292Z","caller":"traceutil/trace.go:171","msg":"trace[1901765728] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"277.621921ms","start":"2026-08-20T02:03:44.713635Z","end":"2026-08-20T02:03:44.991257Z","steps":["trace[1901765728] 'process raft request' (duration: 277.111637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:44.992157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.805133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:44.992223Z","caller":"traceutil/trace.go:171","msg":"trace[1856301301] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25432; }","duration":"284.029189ms","start":"2026-08-20T02:03:44.708176Z","end":"2026-08-20T02:03:44.992205Z","steps":["trace[1856301301] 'agreement among raft nodes before linearized reading' (duration: 283.143675ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:45.013713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.167132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:45.013767Z","caller":"traceutil/trace.go:171","msg":"trace[6001726] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25433; }","duration":"303.254594ms","start":"2026-08-20T02:03:44.7105Z","end":"2026-08-20T02:03:45.013754Z","steps":["trace[6001726] 'agreement among raft nodes before linearized reading' (duration: 303.126431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:45.013789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:44.710483Z","time spent":"303.300425ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:03:45.013714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.635745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:45.013848Z","caller":"traceutil/trace.go:171","msg":"trace[1170959578] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25433; }","duration":"303.81293ms","start":"2026-08-20T02:03:44.709957Z","end":"2026-08-20T02:03:45.013827Z","steps":["trace[1170959578] 'agreement among raft nodes before linearized reading' (duration: 303.518992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:45.013908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:44.709937Z","time spent":"303.950004ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:03:45.436499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.961046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:45.436814Z","caller":"traceutil/trace.go:171","msg":"trace[1543277263] linearizableReadLoop","detail":"{readStateIndex:26257; appliedIndex:26253; }","duration":"260.048942ms","start":"2026-08-20T02:03:45.176744Z","end":"2026-08-20T02:03:45.436793Z","steps":["trace[1543277263] 'read index received' (duration: 115.748993ms)","trace[1543277263] 'applied index is now lower than readState.Index' (duration: 144.298569ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:45.43697Z","caller":"traceutil/trace.go:171","msg":"trace[1230683804] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"417.379923ms","start":"2026-08-20T02:03:45.019559Z","end":"2026-08-20T02:03:45.436938Z","steps":["trace[1230683804] 'process raft request' (duration: 417.187917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:45.437117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:45.01953Z","time spent":"417.503596ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:45.437175Z","caller":"traceutil/trace.go:171","msg":"trace[549003002] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"417.712872ms","start":"2026-08-20T02:03:45.019438Z","end":"2026-08-20T02:03:45.437151Z","steps":["trace[549003002] 'process raft request' (duration: 417.179167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:45.43719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.434372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T02:03:45.437241Z","caller":"traceutil/trace.go:171","msg":"trace[870421849] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"418.447312ms","start":"2026-08-20T02:03:45.018769Z","end":"2026-08-20T02:03:45.437216Z","steps":["trace[870421849] 'process raft request' (duration: 146.676064ms)","trace[870421849] 'compare' (duration: 270.836732ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:45.43725Z","caller":"traceutil/trace.go:171","msg":"trace[1363220141] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:25437; }","duration":"260.532955ms","start":"2026-08-20T02:03:45.176701Z","end":"2026-08-20T02:03:45.437234Z","steps":["trace[1363220141] 'agreement among raft nodes before linearized reading' (duration: 260.404611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:45.437271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:45.019431Z","time spent":"417.789164ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:45.437224Z","caller":"traceutil/trace.go:171","msg":"trace[893840766] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"417.665721ms","start":"2026-08-20T02:03:45.019492Z","end":"2026-08-20T02:03:45.437157Z","steps":["trace[893840766] 'process raft request' (duration: 417.206858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:45.437333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:45.018743Z","time spent":"418.542975ms","remote":"127.0.0.1:53846","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-20T02:03:45.437492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:45.019487Z","time spent":"417.844336ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:46.193744Z","caller":"traceutil/trace.go:171","msg":"trace[1016869425] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"289.054699ms","start":"2026-08-20T02:03:45.904657Z","end":"2026-08-20T02:03:46.193712Z","steps":["trace[1016869425] 'process raft request' (duration: 288.861354ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:46.79768Z","caller":"traceutil/trace.go:171","msg":"trace[145550698] linearizableReadLoop","detail":"{readStateIndex:26259; appliedIndex:26258; }","duration":"112.125643ms","start":"2026-08-20T02:03:46.685521Z","end":"2026-08-20T02:03:46.797647Z","steps":["trace[145550698] 'read index received' (duration: 111.869625ms)","trace[145550698] 'applied index is now lower than readState.Index' (duration: 254.748µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:46.797773Z","caller":"traceutil/trace.go:171","msg":"trace[1592864220] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"145.588554ms","start":"2026-08-20T02:03:46.652152Z","end":"2026-08-20T02:03:46.79774Z","steps":["trace[1592864220] 'process raft request' (duration: 145.265265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:46.797931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.40979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-20T02:03:46.79795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.467719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:46.797976Z","caller":"traceutil/trace.go:171","msg":"trace[839147136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25439; }","duration":"112.495582ms","start":"2026-08-20T02:03:46.685468Z","end":"2026-08-20T02:03:46.797963Z","steps":["trace[839147136] 'agreement among raft nodes before linearized reading' (duration: 112.319308ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:46.798014Z","caller":"traceutil/trace.go:171","msg":"trace[2030239441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25439; }","duration":"104.590462ms","start":"2026-08-20T02:03:46.693404Z","end":"2026-08-20T02:03:46.797995Z","steps":["trace[2030239441] 'agreement among raft nodes before linearized reading' (duration: 104.457179ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:47.114735Z","caller":"traceutil/trace.go:171","msg":"trace[1891148768] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"116.955666ms","start":"2026-08-20T02:03:46.997749Z","end":"2026-08-20T02:03:47.114705Z","steps":["trace[1891148768] 'process raft request' (duration: 116.845643ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:47.11474Z","caller":"traceutil/trace.go:171","msg":"trace[2019360527] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"117.887623ms","start":"2026-08-20T02:03:46.996804Z","end":"2026-08-20T02:03:47.114692Z","steps":["trace[2019360527] 'process raft request' (duration: 117.617366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:47.11511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.50916ms","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-20T02:03:47.115161Z","caller":"traceutil/trace.go:171","msg":"trace[1611929048] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25444; }","duration":"118.592992ms","start":"2026-08-20T02:03:46.996557Z","end":"2026-08-20T02:03:47.11515Z","steps":["trace[1611929048] 'agreement among raft nodes before linearized reading' (duration: 118.310194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:47.115186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.070598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:47.115291Z","caller":"traceutil/trace.go:171","msg":"trace[320679686] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25444; }","duration":"118.186401ms","start":"2026-08-20T02:03:46.997085Z","end":"2026-08-20T02:03:47.115271Z","steps":["trace[320679686] 'agreement among raft nodes before linearized reading' (duration: 117.948935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:47.278178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.632591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:47.278312Z","caller":"traceutil/trace.go:171","msg":"trace[1396316761] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"159.243265ms","start":"2026-08-20T02:03:47.119035Z","end":"2026-08-20T02:03:47.278278Z","steps":["trace[1396316761] 'process raft request' (duration: 20.441669ms)","trace[1396316761] 'compare' (duration: 138.409155ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:47.575792Z","caller":"traceutil/trace.go:171","msg":"trace[843207429] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"130.604516ms","start":"2026-08-20T02:03:47.445161Z","end":"2026-08-20T02:03:47.575765Z","steps":["trace[843207429] 'process raft request' (duration: 130.416601ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:47.879778Z","caller":"traceutil/trace.go:171","msg":"trace[968948915] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"434.606433ms","start":"2026-08-20T02:03:47.44515Z","end":"2026-08-20T02:03:47.879756Z","steps":["trace[968948915] 'process raft request' (duration: 434.392227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:47.879909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:47.445116Z","time spent":"434.725296ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:47.880071Z","caller":"traceutil/trace.go:171","msg":"trace[1902160905] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"434.105338ms","start":"2026-08-20T02:03:47.44592Z","end":"2026-08-20T02:03:47.880025Z","steps":["trace[1902160905] 'process raft request' (duration: 433.780029ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:47.88016Z","caller":"traceutil/trace.go:171","msg":"trace[371808046] linearizableReadLoop","detail":"{readStateIndex:26270; appliedIndex:26266; }","duration":"341.233732ms","start":"2026-08-20T02:03:47.538901Z","end":"2026-08-20T02:03:47.880135Z","steps":["trace[371808046] 'read index received' (duration: 36.755053ms)","trace[371808046] 'applied index is now lower than readState.Index' (duration: 304.477319ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:47.88025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:47.445906Z","time spent":"434.251623ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:47.880284Z","caller":"traceutil/trace.go:171","msg":"trace[1793711555] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"433.387448ms","start":"2026-08-20T02:03:47.446857Z","end":"2026-08-20T02:03:47.880245Z","steps":["trace[1793711555] 'process raft request' (duration: 433.08175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:47.88032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.409047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:47.880362Z","caller":"traceutil/trace.go:171","msg":"trace[270231333] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25450; }","duration":"341.50856ms","start":"2026-08-20T02:03:47.53884Z","end":"2026-08-20T02:03:47.880349Z","steps":["trace[270231333] 'agreement among raft nodes before linearized reading' (duration: 341.422498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:47.880361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.081522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:03:47.880395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:47.538817Z","time spent":"341.568561ms","remote":"127.0.0.1:57412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:03:47.880401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:47.446849Z","time spent":"433.490721ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:47.880427Z","caller":"traceutil/trace.go:171","msg":"trace[2109186077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25450; }","duration":"186.199465ms","start":"2026-08-20T02:03:47.694211Z","end":"2026-08-20T02:03:47.880411Z","steps":["trace[2109186077] 'agreement among raft nodes before linearized reading' (duration: 186.075631ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:48.406692Z","caller":"traceutil/trace.go:171","msg":"trace[414843270] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"207.905978ms","start":"2026-08-20T02:03:48.198764Z","end":"2026-08-20T02:03:48.40667Z","steps":["trace[414843270] 'process raft request' (duration: 207.736933ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:48.951887Z","caller":"traceutil/trace.go:171","msg":"trace[1911208594] linearizableReadLoop","detail":"{readStateIndex:26273; appliedIndex:26272; }","duration":"128.514009ms","start":"2026-08-20T02:03:48.823345Z","end":"2026-08-20T02:03:48.951859Z","steps":["trace[1911208594] 'read index received' (duration: 128.135988ms)","trace[1911208594] 'applied index is now lower than readState.Index' (duration: 376.671µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:48.952037Z","caller":"traceutil/trace.go:171","msg":"trace[349847782] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"147.071056ms","start":"2026-08-20T02:03:48.804924Z","end":"2026-08-20T02:03:48.951995Z","steps":["trace[349847782] 'process raft request' (duration: 146.743386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:48.952134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.788746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-20T02:03:48.952173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.013056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T02:03:48.952185Z","caller":"traceutil/trace.go:171","msg":"trace[1062488310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25452; }","duration":"128.857359ms","start":"2026-08-20T02:03:48.823316Z","end":"2026-08-20T02:03:48.952174Z","steps":["trace[1062488310] 'agreement among raft nodes before linearized reading' (duration: 128.689924ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:48.952296Z","caller":"traceutil/trace.go:171","msg":"trace[675929701] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:25452; }","duration":"104.193351ms","start":"2026-08-20T02:03:48.848084Z","end":"2026-08-20T02:03:48.952277Z","steps":["trace[675929701] 'agreement among raft nodes before linearized reading' (duration: 103.978875ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:49.056018Z","caller":"traceutil/trace.go:171","msg":"trace[658414764] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"100.025426ms","start":"2026-08-20T02:03:48.955976Z","end":"2026-08-20T02:03:49.056002Z","steps":["trace[658414764] 'process raft request' (duration: 92.810855ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:49.489131Z","caller":"traceutil/trace.go:171","msg":"trace[1430516950] linearizableReadLoop","detail":"{readStateIndex:26276; appliedIndex:26275; }","duration":"366.614329ms","start":"2026-08-20T02:03:49.122488Z","end":"2026-08-20T02:03:49.489102Z","steps":["trace[1430516950] 'read index received' (duration: 366.496606ms)","trace[1430516950] 'applied index is now lower than readState.Index' (duration: 116.513µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:49.489247Z","caller":"traceutil/trace.go:171","msg":"trace[258893936] transaction","detail":"{read_only:false; response_revision:25455; number_of_response:1; }","duration":"366.794633ms","start":"2026-08-20T02:03:49.122421Z","end":"2026-08-20T02:03:49.489216Z","steps":["trace[258893936] 'process raft request' (duration: 366.520906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:49.489361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.834445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-20T02:03:49.489379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:49.122357Z","time spent":"366.949078ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:49.489393Z","caller":"traceutil/trace.go:171","msg":"trace[940365050] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25455; }","duration":"366.920127ms","start":"2026-08-20T02:03:49.122464Z","end":"2026-08-20T02:03:49.489385Z","steps":["trace[940365050] 'agreement among raft nodes before linearized reading' (duration: 366.735852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:49.489514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:49.122454Z","time spent":"367.046271ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-20T02:03:49.508012Z","caller":"traceutil/trace.go:171","msg":"trace[330213242] transaction","detail":"{read_only:false; response_revision:25456; number_of_response:1; }","duration":"384.850476ms","start":"2026-08-20T02:03:49.123149Z","end":"2026-08-20T02:03:49.507999Z","steps":["trace[330213242] 'process raft request' (duration: 383.854699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:49.508089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:49.123131Z","time spent":"384.916559ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:49.508454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.288588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:49.508487Z","caller":"traceutil/trace.go:171","msg":"trace[182101337] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"385.297929ms","start":"2026-08-20T02:03:49.12317Z","end":"2026-08-20T02:03:49.508468Z","steps":["trace[182101337] 'process raft request' (duration: 384.788545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:49.508549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.228211ms","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-20T02:03:49.508562Z","caller":"traceutil/trace.go:171","msg":"trace[1141484765] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25458; }","duration":"385.428573ms","start":"2026-08-20T02:03:49.123116Z","end":"2026-08-20T02:03:49.508544Z","steps":["trace[1141484765] 'agreement among raft nodes before linearized reading' (duration: 385.180446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:49.50858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:49.123152Z","time spent":"385.401372ms","remote":"127.0.0.1:53846","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-20T02:03:49.508554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.627403ms","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-20T02:03:49.508593Z","caller":"traceutil/trace.go:171","msg":"trace[332357608] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25458; }","duration":"225.316085ms","start":"2026-08-20T02:03:49.283266Z","end":"2026-08-20T02:03:49.508582Z","steps":["trace[332357608] 'agreement among raft nodes before linearized reading' (duration: 225.13817ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:49.508635Z","caller":"traceutil/trace.go:171","msg":"trace[290325896] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25458; }","duration":"304.754897ms","start":"2026-08-20T02:03:49.203863Z","end":"2026-08-20T02:03:49.508618Z","steps":["trace[290325896] 'agreement among raft nodes before linearized reading' (duration: 304.487119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:49.508681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.516986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-20T02:03:49.508682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:49.203843Z","time spent":"304.830858ms","remote":"127.0.0.1:53754","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":"info","ts":"2026-08-20T02:03:49.508718Z","caller":"traceutil/trace.go:171","msg":"trace[1914685697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25458; }","duration":"380.568587ms","start":"2026-08-20T02:03:49.128141Z","end":"2026-08-20T02:03:49.508709Z","steps":["trace[1914685697] 'agreement among raft nodes before linearized reading' (duration: 380.481325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:49.508762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:49.128132Z","time spent":"380.609889ms","remote":"127.0.0.1:53846","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-20T02:03:49.508808Z","caller":"traceutil/trace.go:171","msg":"trace[7693224] transaction","detail":"{read_only:false; response_revision:25458; number_of_response:1; }","duration":"385.516386ms","start":"2026-08-20T02:03:49.12328Z","end":"2026-08-20T02:03:49.508796Z","steps":["trace[7693224] 'process raft request' (duration: 384.908809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:49.508602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:49.123093Z","time spent":"385.499165ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:03:49.508949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:49.123249Z","time spent":"385.634978ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:49.800921Z","caller":"traceutil/trace.go:171","msg":"trace[1871076643] linearizableReadLoop","detail":"{readStateIndex:26281; appliedIndex:26280; }","duration":"232.120834ms","start":"2026-08-20T02:03:49.568763Z","end":"2026-08-20T02:03:49.800884Z","steps":["trace[1871076643] 'read index received' (duration: 208.451095ms)","trace[1871076643] 'applied index is now lower than readState.Index' (duration: 23.667849ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:49.80097Z","caller":"traceutil/trace.go:171","msg":"trace[1443384402] transaction","detail":"{read_only:false; response_revision:25460; number_of_response:1; }","duration":"286.051685ms","start":"2026-08-20T02:03:49.51487Z","end":"2026-08-20T02:03:49.800922Z","steps":["trace[1443384402] 'process raft request' (duration: 262.449608ms)","trace[1443384402] 'compare' (duration: 23.119184ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:49.801195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.428192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-08-20T02:03:49.801227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.710956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-20T02:03:49.801248Z","caller":"traceutil/trace.go:171","msg":"trace[1164549924] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25460; }","duration":"232.541045ms","start":"2026-08-20T02:03:49.568693Z","end":"2026-08-20T02:03:49.801234Z","steps":["trace[1164549924] 'agreement among raft nodes before linearized reading' (duration: 232.315469ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:49.801295Z","caller":"traceutil/trace.go:171","msg":"trace[867869819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25460; }","duration":"220.817169ms","start":"2026-08-20T02:03:49.580441Z","end":"2026-08-20T02:03:49.801259Z","steps":["trace[867869819] 'agreement among raft nodes before linearized reading' (duration: 220.658574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:49.801456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.628099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:03:49.801463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.475128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:49.801516Z","caller":"traceutil/trace.go:171","msg":"trace[17918589] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25460; }","duration":"213.753323ms","start":"2026-08-20T02:03:49.587749Z","end":"2026-08-20T02:03:49.801503Z","steps":["trace[17918589] 'agreement among raft nodes before linearized reading' (duration: 213.65574ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:49.801528Z","caller":"traceutil/trace.go:171","msg":"trace[1209536197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25460; }","duration":"105.600741ms","start":"2026-08-20T02:03:49.695911Z","end":"2026-08-20T02:03:49.801512Z","steps":["trace[1209536197] 'agreement among raft nodes before linearized reading' (duration: 105.490918ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:50.098202Z","caller":"traceutil/trace.go:171","msg":"trace[463345220] transaction","detail":"{read_only:false; response_revision:25463; number_of_response:1; }","duration":"210.275266ms","start":"2026-08-20T02:03:49.887894Z","end":"2026-08-20T02:03:50.09817Z","steps":["trace[463345220] 'process raft request' (duration: 128.103228ms)","trace[463345220] 'compare' (duration: 82.031164ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:50.159453Z","caller":"traceutil/trace.go:171","msg":"trace[1118907928] linearizableReadLoop","detail":"{readStateIndex:26286; appliedIndex:26284; }","duration":"127.649685ms","start":"2026-08-20T02:03:50.031784Z","end":"2026-08-20T02:03:50.159434Z","steps":["trace[1118907928] 'read index received' (duration: 127.49779ms)","trace[1118907928] 'applied index is now lower than readState.Index' (duration: 151.035µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:50.159486Z","caller":"traceutil/trace.go:171","msg":"trace[1266249438] transaction","detail":"{read_only:false; response_revision:25464; number_of_response:1; }","duration":"270.566614ms","start":"2026-08-20T02:03:49.888898Z","end":"2026-08-20T02:03:50.159465Z","steps":["trace[1266249438] 'process raft request' (duration: 270.39255ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:50.159533Z","caller":"traceutil/trace.go:171","msg":"trace[1052760953] transaction","detail":"{read_only:false; response_revision:25465; number_of_response:1; }","duration":"269.389781ms","start":"2026-08-20T02:03:49.890108Z","end":"2026-08-20T02:03:50.159498Z","steps":["trace[1052760953] 'process raft request' (duration: 269.277108ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:50.159776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.940165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:03:50.159807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.948643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-20T02:03:50.159844Z","caller":"traceutil/trace.go:171","msg":"trace[1217413481] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25465; }","duration":"128.079057ms","start":"2026-08-20T02:03:50.031754Z","end":"2026-08-20T02:03:50.159833Z","steps":["trace[1217413481] 'agreement among raft nodes before linearized reading' (duration: 127.795119ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:50.15983Z","caller":"traceutil/trace.go:171","msg":"trace[958598558] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25465; }","duration":"108.059139ms","start":"2026-08-20T02:03:50.051757Z","end":"2026-08-20T02:03:50.159817Z","steps":["trace[958598558] 'agreement among raft nodes before linearized reading' (duration: 107.961537ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:54.900931Z","caller":"traceutil/trace.go:171","msg":"trace[934761819] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"407.282612ms","start":"2026-08-20T02:03:54.493608Z","end":"2026-08-20T02:03:54.900891Z","steps":["trace[934761819] 'process raft request' (duration: 406.801858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:54.901094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:54.493583Z","time spent":"407.425625ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:55.442973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.238378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:55.443141Z","caller":"traceutil/trace.go:171","msg":"trace[1725615261] linearizableReadLoop","detail":"{readStateIndex:26326; appliedIndex:26325; }","duration":"459.505516ms","start":"2026-08-20T02:03:54.983611Z","end":"2026-08-20T02:03:55.443116Z","steps":["trace[1725615261] 'read index received' (duration: 218.002331ms)","trace[1725615261] 'applied index is now lower than readState.Index' (duration: 241.500645ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:55.443177Z","caller":"traceutil/trace.go:171","msg":"trace[840013225] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"461.576283ms","start":"2026-08-20T02:03:54.981551Z","end":"2026-08-20T02:03:55.443128Z","steps":["trace[840013225] 'process raft request' (duration: 220.036947ms)","trace[840013225] 'compare' (duration: 241.004241ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:55.443379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:54.981524Z","time spent":"461.734488ms","remote":"127.0.0.1:53846","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-20T02:03:55.443399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.763733ms","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-20T02:03:55.443473Z","caller":"traceutil/trace.go:171","msg":"trace[743894360] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25503; }","duration":"459.864265ms","start":"2026-08-20T02:03:54.98359Z","end":"2026-08-20T02:03:55.443454Z","steps":["trace[743894360] 'agreement among raft nodes before linearized reading' (duration: 459.617598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:55.443529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:54.983579Z","time spent":"459.941318ms","remote":"127.0.0.1:53754","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":"info","ts":"2026-08-20T02:03:55.464568Z","caller":"traceutil/trace.go:171","msg":"trace[1405497361] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"386.897953ms","start":"2026-08-20T02:03:55.077635Z","end":"2026-08-20T02:03:55.464533Z","steps":["trace[1405497361] 'process raft request' (duration: 386.612255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:55.464625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.365697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-20T02:03:55.464668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.213863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:55.464697Z","caller":"traceutil/trace.go:171","msg":"trace[78355746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25504; }","duration":"377.491ms","start":"2026-08-20T02:03:55.087189Z","end":"2026-08-20T02:03:55.46468Z","steps":["trace[78355746] 'agreement among raft nodes before linearized reading' (duration: 377.286635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:55.464739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:55.087149Z","time spent":"377.582133ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-20T02:03:55.464745Z","caller":"traceutil/trace.go:171","msg":"trace[498925832] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25504; }","duration":"321.342318ms","start":"2026-08-20T02:03:55.143384Z","end":"2026-08-20T02:03:55.464726Z","steps":["trace[498925832] 'agreement among raft nodes before linearized reading' (duration: 321.096541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:55.464749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:55.0776Z","time spent":"387.056708ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:55.464787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:55.143359Z","time spent":"321.420129ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:03:55.465019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.223306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:55.465087Z","caller":"traceutil/trace.go:171","msg":"trace[1332164457] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:25504; }","duration":"292.33796ms","start":"2026-08-20T02:03:55.172727Z","end":"2026-08-20T02:03:55.465065Z","steps":["trace[1332164457] 'agreement among raft nodes before linearized reading' (duration: 292.229337ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:55.810871Z","caller":"traceutil/trace.go:171","msg":"trace[615629425] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"272.252622ms","start":"2026-08-20T02:03:55.538588Z","end":"2026-08-20T02:03:55.81084Z","steps":["trace[615629425] 'process raft request' (duration: 259.904128ms)","trace[615629425] 'compare' (duration: 11.730786ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:56.065042Z","caller":"traceutil/trace.go:171","msg":"trace[1845029372] linearizableReadLoop","detail":"{readStateIndex:26331; appliedIndex:26328; }","duration":"497.819353ms","start":"2026-08-20T02:03:55.567197Z","end":"2026-08-20T02:03:56.065016Z","steps":["trace[1845029372] 'read index received' (duration: 231.313151ms)","trace[1845029372] 'applied index is now lower than readState.Index' (duration: 266.505002ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:56.065178Z","caller":"traceutil/trace.go:171","msg":"trace[919019949] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"526.385658ms","start":"2026-08-20T02:03:55.53878Z","end":"2026-08-20T02:03:56.065166Z","steps":["trace[919019949] 'process raft request' (duration: 525.167804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:56.065285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:55.538748Z","time spent":"526.45874ms","remote":"127.0.0.1:53846","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-20T02:03:56.065501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.284316ms","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-20T02:03:56.065595Z","caller":"traceutil/trace.go:171","msg":"trace[60786822] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25508; }","duration":"498.418359ms","start":"2026-08-20T02:03:55.567148Z","end":"2026-08-20T02:03:56.065566Z","steps":["trace[60786822] 'agreement among raft nodes before linearized reading' (duration: 498.174192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:56.065593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.140865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:03:56.065642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:55.567131Z","time spent":"498.501992ms","remote":"127.0.0.1:53846","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":"warn","ts":"2026-08-20T02:03:56.065649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.889298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-20T02:03:56.065668Z","caller":"traceutil/trace.go:171","msg":"trace[1415667948] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:25508; }","duration":"190.252718ms","start":"2026-08-20T02:03:55.875397Z","end":"2026-08-20T02:03:56.065649Z","steps":["trace[1415667948] 'agreement among raft nodes before linearized reading' (duration: 190.123085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:56.065534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.579262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:56.065691Z","caller":"traceutil/trace.go:171","msg":"trace[1128689354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25508; }","duration":"212.96723ms","start":"2026-08-20T02:03:55.852712Z","end":"2026-08-20T02:03:56.065679Z","steps":["trace[1128689354] 'agreement among raft nodes before linearized reading' (duration: 212.845747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:56.065803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.551474ms","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-20T02:03:56.065774Z","caller":"traceutil/trace.go:171","msg":"trace[1012289887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25508; }","duration":"369.818848ms","start":"2026-08-20T02:03:55.695897Z","end":"2026-08-20T02:03:56.065715Z","steps":["trace[1012289887] 'agreement among raft nodes before linearized reading' (duration: 369.599882ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:56.065874Z","caller":"traceutil/trace.go:171","msg":"trace[1198197043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25508; }","duration":"233.662787ms","start":"2026-08-20T02:03:55.832195Z","end":"2026-08-20T02:03:56.065858Z","steps":["trace[1198197043] 'agreement among raft nodes before linearized reading' (duration: 233.459682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:56.065933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:55.695868Z","time spent":"370.007054ms","remote":"127.0.0.1:53560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-20T02:03:56.065732Z","caller":"traceutil/trace.go:171","msg":"trace[651286069] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"524.684601ms","start":"2026-08-20T02:03:55.541011Z","end":"2026-08-20T02:03:56.065695Z","steps":["trace[651286069] 'process raft request' (duration: 523.905489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:56.066308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:55.540986Z","time spent":"525.244287ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:04:05.793622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.068289ms","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-20T02:04:05.793725Z","caller":"traceutil/trace.go:171","msg":"trace[928515644] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25636; }","duration":"127.205452ms","start":"2026-08-20T02:04:05.666499Z","end":"2026-08-20T02:04:05.793704Z","steps":["trace[928515644] 'range keys from in-memory index tree' (duration: 126.879004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:05.793842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.977651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a7ef0af-1750-40e3-8b79-11eb8f94470e\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-20T02:04:05.793926Z","caller":"traceutil/trace.go:171","msg":"trace[1456039423] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a7ef0af-1750-40e3-8b79-11eb8f94470e; range_end:; response_count:1; response_revision:25636; }","duration":"143.111546ms","start":"2026-08-20T02:04:05.650791Z","end":"2026-08-20T02:04:05.793903Z","steps":["trace[1456039423] 'range keys from in-memory index tree' (duration: 142.795156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:06.030606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.09994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-08-20T02:04:06.030708Z","caller":"traceutil/trace.go:171","msg":"trace[10061535] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25637; }","duration":"136.256024ms","start":"2026-08-20T02:04:05.894433Z","end":"2026-08-20T02:04:06.030689Z","steps":["trace[10061535] 'range keys from in-memory index tree' (duration: 135.929775ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:06.301489Z","caller":"traceutil/trace.go:171","msg":"trace[1107831227] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"193.391564ms","start":"2026-08-20T02:04:06.108069Z","end":"2026-08-20T02:04:06.30146Z","steps":["trace[1107831227] 'process raft request' (duration: 193.179248ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:06.306566Z","caller":"traceutil/trace.go:171","msg":"trace[1444796663] linearizableReadLoop","detail":"{readStateIndex:26464; appliedIndex:26462; }","duration":"189.857646ms","start":"2026-08-20T02:04:06.116687Z","end":"2026-08-20T02:04:06.306545Z","steps":["trace[1444796663] 'read index received' (duration: 184.60568ms)","trace[1444796663] 'applied index is now lower than readState.Index' (duration: 5.250706ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:06.306656Z","caller":"traceutil/trace.go:171","msg":"trace[525750787] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"197.145348ms","start":"2026-08-20T02:04:06.109455Z","end":"2026-08-20T02:04:06.306601Z","steps":["trace[525750787] 'process raft request' (duration: 196.995244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:06.306797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.104522ms","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-20T02:04:06.306849Z","caller":"traceutil/trace.go:171","msg":"trace[1055212371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25639; }","duration":"190.214966ms","start":"2026-08-20T02:04:06.11662Z","end":"2026-08-20T02:04:06.306835Z","steps":["trace[1055212371] 'agreement among raft nodes before linearized reading' (duration: 190.01412ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:06.306896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.122359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-20T02:04:06.306953Z","caller":"traceutil/trace.go:171","msg":"trace[1173080386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25639; }","duration":"136.233723ms","start":"2026-08-20T02:04:06.170706Z","end":"2026-08-20T02:04:06.30694Z","steps":["trace[1173080386] 'agreement among raft nodes before linearized reading' (duration: 136.101049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:06.306892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.463574ms","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-20T02:04:06.307039Z","caller":"traceutil/trace.go:171","msg":"trace[218241449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25639; }","duration":"189.64108ms","start":"2026-08-20T02:04:06.117382Z","end":"2026-08-20T02:04:06.307023Z","steps":["trace[218241449] 'agreement among raft nodes before linearized reading' (duration: 189.353082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:06.307121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.849724ms","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-20T02:04:06.307231Z","caller":"traceutil/trace.go:171","msg":"trace[1178307952] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25639; }","duration":"186.027479ms","start":"2026-08-20T02:04:06.121173Z","end":"2026-08-20T02:04:06.307201Z","steps":["trace[1178307952] 'agreement among raft nodes before linearized reading' (duration: 185.69805ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:06.584791Z","caller":"traceutil/trace.go:171","msg":"trace[845308845] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"273.683761ms","start":"2026-08-20T02:04:06.311079Z","end":"2026-08-20T02:04:06.584762Z","steps":["trace[845308845] 'process raft request' (duration: 205.346338ms)","trace[845308845] 'compare' (duration: 68.21884ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:06.850376Z","caller":"traceutil/trace.go:171","msg":"trace[2000518898] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"537.762034ms","start":"2026-08-20T02:04:06.312533Z","end":"2026-08-20T02:04:06.850295Z","steps":["trace[2000518898] 'process raft request' (duration: 537.541708ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:06.850495Z","caller":"traceutil/trace.go:171","msg":"trace[436469736] transaction","detail":"{read_only:false; response_revision:25643; number_of_response:1; }","duration":"537.59611ms","start":"2026-08-20T02:04:06.31287Z","end":"2026-08-20T02:04:06.850466Z","steps":["trace[436469736] 'process raft request' (duration: 537.257641ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:06.85048Z","caller":"traceutil/trace.go:171","msg":"trace[460917417] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"537.439755ms","start":"2026-08-20T02:04:06.313Z","end":"2026-08-20T02:04:06.85044Z","steps":["trace[460917417] 'process raft request' (duration: 537.163428ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:06.850513Z","caller":"traceutil/trace.go:171","msg":"trace[1217405337] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"537.096846ms","start":"2026-08-20T02:04:06.313378Z","end":"2026-08-20T02:04:06.850474Z","steps":["trace[1217405337] 'process raft request' (duration: 536.817039ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:06.850559Z","caller":"traceutil/trace.go:171","msg":"trace[1006416322] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"538.029012ms","start":"2026-08-20T02:04:06.312467Z","end":"2026-08-20T02:04:06.850496Z","steps":["trace[1006416322] 'process raft request' (duration: 447.225683ms)","trace[1006416322] 'compare' (duration: 90.223963ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:06.850609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:06.312512Z","time spent":"537.980461ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:04:06.850636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:06.31286Z","time spent":"537.690923ms","remote":"127.0.0.1:53846","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-20T02:04:06.850636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:06.312976Z","time spent":"537.57551ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:04:06.850695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:06.313368Z","time spent":"537.219ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:04:06.850669Z","caller":"traceutil/trace.go:171","msg":"trace[527116320] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"535.358678ms","start":"2026-08-20T02:04:06.315267Z","end":"2026-08-20T02:04:06.850625Z","steps":["trace[527116320] 'process raft request' (duration: 535.015798ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:06.850722Z","caller":"traceutil/trace.go:171","msg":"trace[1492161415] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"363.205634ms","start":"2026-08-20T02:04:06.48747Z","end":"2026-08-20T02:04:06.850675Z","steps":["trace[1492161415] 'process raft request' (duration: 363.085681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:06.850807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:06.312444Z","time spent":"538.238468ms","remote":"127.0.0.1:53846","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-20T02:04:06.850889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:06.315253Z","time spent":"535.528622ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:04:06.850953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:06.487447Z","time spent":"363.371569ms","remote":"127.0.0.1:53846","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-20T02:04:06.850603Z","caller":"traceutil/trace.go:171","msg":"trace[804122325] transaction","detail":"{read_only:false; response_revision:25646; number_of_response:1; }","duration":"536.453708ms","start":"2026-08-20T02:04:06.314082Z","end":"2026-08-20T02:04:06.850536Z","steps":["trace[804122325] 'process raft request' (duration: 536.1498ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:06.851447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:06.314071Z","time spent":"537.319702ms","remote":"127.0.0.1:53754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3746,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:04:07.834017Z","caller":"traceutil/trace.go:171","msg":"trace[1600077603] linearizableReadLoop","detail":"{readStateIndex:26474; appliedIndex:26473; }","duration":"338.927928ms","start":"2026-08-20T02:04:07.495061Z","end":"2026-08-20T02:04:07.833989Z","steps":["trace[1600077603] 'read index received' (duration: 338.802275ms)","trace[1600077603] 'applied index is now lower than readState.Index' (duration: 124.413µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:07.834542Z","caller":"traceutil/trace.go:171","msg":"trace[1138582946] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"349.04927ms","start":"2026-08-20T02:04:07.485478Z","end":"2026-08-20T02:04:07.834528Z","steps":["trace[1138582946] 'process raft request' (duration: 348.293509ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:07.83467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:07.485458Z","time spent":"349.119662ms","remote":"127.0.0.1:53846","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-20T02:04:07.834968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.907005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:07.835001Z","caller":"traceutil/trace.go:171","msg":"trace[141562122] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25649; }","duration":"339.970057ms","start":"2026-08-20T02:04:07.49502Z","end":"2026-08-20T02:04:07.83499Z","steps":["trace[141562122] 'agreement among raft nodes before linearized reading' (duration: 339.837343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:07.835031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:07.495001Z","time spent":"340.023229ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-20T02:04:08.051305Z","caller":"traceutil/trace.go:171","msg":"trace[1969831539] linearizableReadLoop","detail":"{readStateIndex:26475; appliedIndex:26474; }","duration":"217.199438ms","start":"2026-08-20T02:04:07.834089Z","end":"2026-08-20T02:04:08.051288Z","steps":["trace[1969831539] 'read index received' (duration: 152.090355ms)","trace[1969831539] 'applied index is now lower than readState.Index' (duration: 65.108383ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:08.051487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.646517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-20T02:04:08.051489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.804508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:08.051517Z","caller":"traceutil/trace.go:171","msg":"trace[202999565] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25650; }","duration":"488.715229ms","start":"2026-08-20T02:04:07.562794Z","end":"2026-08-20T02:04:08.051509Z","steps":["trace[202999565] 'agreement among raft nodes before linearized reading' (duration: 488.578755ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:08.051543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:07.562778Z","time spent":"488.75959ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-20T02:04:08.051541Z","caller":"traceutil/trace.go:171","msg":"trace[331387131] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25650; }","duration":"485.86843ms","start":"2026-08-20T02:04:07.565657Z","end":"2026-08-20T02:04:08.051525Z","steps":["trace[331387131] 'agreement among raft nodes before linearized reading' (duration: 485.700295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:08.051578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:07.565646Z","time spent":"485.923142ms","remote":"127.0.0.1:53736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:04:08.051722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.043893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T02:04:08.051744Z","caller":"traceutil/trace.go:171","msg":"trace[457019237] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25650; }","duration":"501.120764ms","start":"2026-08-20T02:04:07.550617Z","end":"2026-08-20T02:04:08.051738Z","steps":["trace[457019237] 'agreement among raft nodes before linearized reading' (duration: 501.082303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:08.051763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:07.5506Z","time spent":"501.157186ms","remote":"127.0.0.1:53892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:04:08.051759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.735033ms","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-20T02:04:08.051801Z","caller":"traceutil/trace.go:171","msg":"trace[407701439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25650; }","duration":"431.787984ms","start":"2026-08-20T02:04:07.62Z","end":"2026-08-20T02:04:08.051788Z","steps":["trace[407701439] 'agreement among raft nodes before linearized reading' (duration: 431.675391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:08.051844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:07.619992Z","time spent":"431.843885ms","remote":"127.0.0.1:53846","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":"warn","ts":"2026-08-20T02:04:08.051453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.625401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:04:08.052021Z","caller":"traceutil/trace.go:171","msg":"trace[50312922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25650; }","duration":"357.235788ms","start":"2026-08-20T02:04:07.694773Z","end":"2026-08-20T02:04:08.052009Z","steps":["trace[50312922] 'agreement among raft nodes before linearized reading' (duration: 356.57935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:08.052057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:07.694756Z","time spent":"357.292269ms","remote":"127.0.0.1:53572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-20T02:04:08.052867Z","caller":"traceutil/trace.go:171","msg":"trace[458945174] transaction","detail":"{read_only:false; response_revision:25650; number_of_response:1; }","duration":"547.178338ms","start":"2026-08-20T02:04:07.505641Z","end":"2026-08-20T02:04:08.052819Z","steps":["trace[458945174] 'process raft request' (duration: 480.521431ms)","trace[458945174] 'compare' (duration: 64.831996ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:08.053051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:07.505618Z","time spent":"547.334381ms","remote":"127.0.0.1:53846","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-20T02:04:08.053455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.57873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1a7ef0af-1750-40e3-8b79-11eb8f94470e\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-08-20T02:04:08.053495Z","caller":"traceutil/trace.go:171","msg":"trace[2113024808] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1a7ef0af-1750-40e3-8b79-11eb8f94470e; range_end:; response_count:1; response_revision:25651; }","duration":"198.646061ms","start":"2026-08-20T02:04:07.854837Z","end":"2026-08-20T02:04:08.053483Z","steps":["trace[2113024808] 'agreement among raft nodes before linearized reading' (duration: 198.539208ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:08.053636Z","caller":"traceutil/trace.go:171","msg":"trace[552975472] transaction","detail":"{read_only:false; response_revision:25651; number_of_response:1; }","duration":"173.839321ms","start":"2026-08-20T02:04:07.879778Z","end":"2026-08-20T02:04:08.053618Z","steps":["trace[552975472] 'process raft request' (duration: 173.535122ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:10.018947Z","caller":"traceutil/trace.go:171","msg":"trace[1859350800] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"178.672666ms","start":"2026-08-20T02:04:09.840248Z","end":"2026-08-20T02:04:10.018921Z","steps":["trace[1859350800] 'process raft request' (duration: 178.512291ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:10.019047Z","caller":"traceutil/trace.go:171","msg":"trace[2017913935] linearizableReadLoop","detail":"{readStateIndex:26490; appliedIndex:26490; }","duration":"145.10367ms","start":"2026-08-20T02:04:09.873918Z","end":"2026-08-20T02:04:10.019022Z","steps":["trace[2017913935] 'read index received' (duration: 145.09035ms)","trace[2017913935] 'applied index is now lower than readState.Index' (duration: 11.31µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:10.019317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.370198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-20T02:04:10.019384Z","caller":"traceutil/trace.go:171","msg":"trace[1474710656] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25664; }","duration":"145.45307ms","start":"2026-08-20T02:04:09.873913Z","end":"2026-08-20T02:04:10.019366Z","steps":["trace[1474710656] 'agreement among raft nodes before linearized reading' (duration: 145.213303ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:10.083446Z","caller":"traceutil/trace.go:171","msg":"trace[1930539850] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"155.885061ms","start":"2026-08-20T02:04:09.927535Z","end":"2026-08-20T02:04:10.08342Z","steps":["trace[1930539850] 'process raft request' (duration: 147.93791ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:13.065023Z","caller":"traceutil/trace.go:171","msg":"trace[11960942] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"151.91363ms","start":"2026-08-20T02:04:12.913083Z","end":"2026-08-20T02:04:13.064996Z","steps":["trace[11960942] 'process raft request' (duration: 151.760436ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:13.089717Z","caller":"traceutil/trace.go:171","msg":"trace[2049930426] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"175.870357ms","start":"2026-08-20T02:04:12.913826Z","end":"2026-08-20T02:04:13.089696Z","steps":["trace[2049930426] 'process raft request' (duration: 175.754064ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:13.089905Z","caller":"traceutil/trace.go:171","msg":"trace[1777404964] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"176.112114ms","start":"2026-08-20T02:04:12.913782Z","end":"2026-08-20T02:04:13.089894Z","steps":["trace[1777404964] 'process raft request' (duration: 175.673351ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:13.090126Z","caller":"traceutil/trace.go:171","msg":"trace[507578556] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"173.678657ms","start":"2026-08-20T02:04:12.916427Z","end":"2026-08-20T02:04:13.090105Z","steps":["trace[507578556] 'process raft request' (duration: 173.211854ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:13.090319Z","caller":"traceutil/trace.go:171","msg":"trace[579903492] linearizableReadLoop","detail":"{readStateIndex:26534; appliedIndex:26529; }","duration":"120.32562ms","start":"2026-08-20T02:04:12.969985Z","end":"2026-08-20T02:04:13.090311Z","steps":["trace[579903492] 'read index received' (duration: 94.79667ms)","trace[579903492] 'applied index is now lower than readState.Index' (duration: 25.52812ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:13.090392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.401003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:04:13.090418Z","caller":"traceutil/trace.go:171","msg":"trace[1482487002] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25709; }","duration":"120.485715ms","start":"2026-08-20T02:04:12.969922Z","end":"2026-08-20T02:04:13.090408Z","steps":["trace[1482487002] 'agreement among raft nodes before linearized reading' (duration: 120.416443ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:13.09064Z","caller":"traceutil/trace.go:171","msg":"trace[1507919074] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"172.718629ms","start":"2026-08-20T02:04:12.917914Z","end":"2026-08-20T02:04:13.090633Z","steps":["trace[1507919074] 'process raft request' (duration: 172.118673ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:38.243302Z","caller":"traceutil/trace.go:171","msg":"trace[1616790502] transaction","detail":"{read_only:false; response_revision:25916; number_of_response:1; }","duration":"111.431275ms","start":"2026-08-20T02:04:38.131834Z","end":"2026-08-20T02:04:38.243265Z","steps":["trace[1616790502] 'process raft request' (duration: 111.195238ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:38.318683Z","caller":"traceutil/trace.go:171","msg":"trace[1879100148] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"105.825889ms","start":"2026-08-20T02:04:38.212804Z","end":"2026-08-20T02:04:38.31863Z","steps":["trace[1879100148] 'process raft request' (duration: 105.712016ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:38.31876Z","caller":"traceutil/trace.go:171","msg":"trace[1059850109] transaction","detail":"{read_only:false; response_revision:25917; number_of_response:1; }","duration":"143.155239ms","start":"2026-08-20T02:04:38.175563Z","end":"2026-08-20T02:04:38.318718Z","steps":["trace[1059850109] 'process raft request' (duration: 142.739998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:38.318862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.586241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:38.31895Z","caller":"traceutil/trace.go:171","msg":"trace[912911652] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25918; }","duration":"138.750666ms","start":"2026-08-20T02:04:38.180178Z","end":"2026-08-20T02:04:38.318928Z","steps":["trace[912911652] 'agreement among raft nodes before linearized reading' (duration: 138.433607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:39.081514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.028665ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-20T02:04:39.081596Z","caller":"traceutil/trace.go:171","msg":"trace[1726899382] linearizableReadLoop","detail":"{readStateIndex:26754; appliedIndex:26753; }","duration":"135.014282ms","start":"2026-08-20T02:04:38.946566Z","end":"2026-08-20T02:04:39.081581Z","steps":["trace[1726899382] 'read index received' (duration: 39.161µs)","trace[1726899382] 'applied index is now lower than readState.Index' (duration: 134.974041ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:39.081712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.145085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-08-20T02:04:39.081734Z","caller":"traceutil/trace.go:171","msg":"trace[1822629304] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25922; }","duration":"135.208757ms","start":"2026-08-20T02:04:38.946517Z","end":"2026-08-20T02:04:39.081725Z","steps":["trace[1822629304] 'agreement among raft nodes before linearized reading' (duration: 135.097364ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:39.429126Z","caller":"traceutil/trace.go:171","msg":"trace[520736852] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"165.561413ms","start":"2026-08-20T02:04:39.263546Z","end":"2026-08-20T02:04:39.429107Z","steps":["trace[520736852] 'process raft request' (duration: 165.524532ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:39.429394Z","caller":"traceutil/trace.go:171","msg":"trace[1544989599] transaction","detail":"{read_only:false; response_revision:25925; number_of_response:1; }","duration":"169.465212ms","start":"2026-08-20T02:04:39.25992Z","end":"2026-08-20T02:04:39.429385Z","steps":["trace[1544989599] 'process raft request' (duration: 142.017677ms)","trace[1544989599] 'compare' (duration: 26.691774ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:39.429476Z","caller":"traceutil/trace.go:171","msg":"trace[1113845269] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"168.616869ms","start":"2026-08-20T02:04:39.260854Z","end":"2026-08-20T02:04:39.429471Z","steps":["trace[1113845269] 'process raft request' (duration: 168.123095ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:39.429545Z","caller":"traceutil/trace.go:171","msg":"trace[724027320] transaction","detail":"{read_only:false; response_revision:25927; number_of_response:1; }","duration":"167.703793ms","start":"2026-08-20T02:04:39.261837Z","end":"2026-08-20T02:04:39.429541Z","steps":["trace[724027320] 'process raft request' (duration: 167.20812ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:40.307487Z","caller":"traceutil/trace.go:171","msg":"trace[1441761978] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"176.238031ms","start":"2026-08-20T02:04:40.131221Z","end":"2026-08-20T02:04:40.307459Z","steps":["trace[1441761978] 'process raft request' (duration: 176.056036ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:40.612838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.772137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:04:40.613016Z","caller":"traceutil/trace.go:171","msg":"trace[239703389] linearizableReadLoop","detail":"{readStateIndex:26764; appliedIndex:26762; }","duration":"228.631471ms","start":"2026-08-20T02:04:40.384369Z","end":"2026-08-20T02:04:40.613Z","steps":["trace[239703389] 'read index received' (duration: 70.70879ms)","trace[239703389] 'applied index is now lower than readState.Index' (duration: 157.921521ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:40.613062Z","caller":"traceutil/trace.go:171","msg":"trace[925714381] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"288.832308ms","start":"2026-08-20T02:04:40.324208Z","end":"2026-08-20T02:04:40.613041Z","steps":["trace[925714381] 'process raft request' (duration: 130.787064ms)","trace[925714381] 'compare' (duration: 157.53874ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:40.613149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.785095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-20T02:04:40.613185Z","caller":"traceutil/trace.go:171","msg":"trace[1585080988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25932; }","duration":"228.853597ms","start":"2026-08-20T02:04:40.384322Z","end":"2026-08-20T02:04:40.613176Z","steps":["trace[1585080988] 'agreement among raft nodes before linearized reading' (duration: 228.731314ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:40.61327Z","caller":"traceutil/trace.go:171","msg":"trace[1504662746] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"288.364376ms","start":"2026-08-20T02:04:40.324897Z","end":"2026-08-20T02:04:40.613261Z","steps":["trace[1504662746] 'process raft request' (duration: 288.041467ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:40.626616Z","caller":"traceutil/trace.go:171","msg":"trace[750084485] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"152.376656ms","start":"2026-08-20T02:04:40.474227Z","end":"2026-08-20T02:04:40.626604Z","steps":["trace[750084485] 'process raft request' (duration: 152.345365ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:40.626906Z","caller":"traceutil/trace.go:171","msg":"trace[1254267718] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"154.260698ms","start":"2026-08-20T02:04:40.4726Z","end":"2026-08-20T02:04:40.62686Z","steps":["trace[1254267718] 'process raft request' (duration: 153.907999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:41.128765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.308954ms","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:6"} {"level":"info","ts":"2026-08-20T02:04:41.128852Z","caller":"traceutil/trace.go:171","msg":"trace[151851658] 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:25936; }","duration":"152.444768ms","start":"2026-08-20T02:04:40.976389Z","end":"2026-08-20T02:04:41.128833Z","steps":["trace[151851658] 'count revisions from in-memory index tree' (duration: 152.242242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:41.128874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.70157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-08-20T02:04:41.128936Z","caller":"traceutil/trace.go:171","msg":"trace[839059651] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25936; }","duration":"117.772582ms","start":"2026-08-20T02:04:41.011148Z","end":"2026-08-20T02:04:41.128921Z","steps":["trace[839059651] 'range keys from in-memory index tree' (duration: 117.626708ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:41.746948Z","caller":"traceutil/trace.go:171","msg":"trace[1726426598] transaction","detail":"{read_only:false; response_revision:25939; number_of_response:1; }","duration":"239.72202ms","start":"2026-08-20T02:04:41.507201Z","end":"2026-08-20T02:04:41.746923Z","steps":["trace[1726426598] 'process raft request' (duration: 169.279157ms)","trace[1726426598] 'compare' (duration: 70.220797ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:41.963441Z","caller":"traceutil/trace.go:171","msg":"trace[1097849675] linearizableReadLoop","detail":"{readStateIndex:26774; appliedIndex:26770; }","duration":"338.973485ms","start":"2026-08-20T02:04:41.624442Z","end":"2026-08-20T02:04:41.963415Z","steps":["trace[1097849675] 'read index received' (duration: 52.171153ms)","trace[1097849675] 'applied index is now lower than readState.Index' (duration: 286.800962ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:41.963626Z","caller":"traceutil/trace.go:171","msg":"trace[335168802] transaction","detail":"{read_only:false; response_revision:25942; number_of_response:1; }","duration":"453.692123ms","start":"2026-08-20T02:04:41.509906Z","end":"2026-08-20T02:04:41.963598Z","steps":["trace[335168802] 'process raft request' (duration: 453.459396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:41.963774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:41.509889Z","time spent":"453.797485ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:04:41.963779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.07807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:04:41.963863Z","caller":"traceutil/trace.go:171","msg":"trace[1634496483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25942; }","duration":"268.197533ms","start":"2026-08-20T02:04:41.69564Z","end":"2026-08-20T02:04:41.963837Z","steps":["trace[1634496483] 'agreement among raft nodes before linearized reading' (duration: 268.056019ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:41.963889Z","caller":"traceutil/trace.go:171","msg":"trace[1101026508] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"456.650295ms","start":"2026-08-20T02:04:41.507224Z","end":"2026-08-20T02:04:41.963874Z","steps":["trace[1101026508] 'process raft request' (duration: 368.962742ms)","trace[1101026508] 'compare' (duration: 86.990674ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:41.963974Z","caller":"traceutil/trace.go:171","msg":"trace[1235607822] transaction","detail":"{read_only:false; response_revision:25941; number_of_response:1; }","duration":"455.37682ms","start":"2026-08-20T02:04:41.508586Z","end":"2026-08-20T02:04:41.963962Z","steps":["trace[1235607822] 'process raft request' (duration: 454.721281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:41.964001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:41.507209Z","time spent":"456.715127ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:04:41.96404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:41.508565Z","time spent":"455.445481ms","remote":"127.0.0.1:53846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:04:41.964266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.81555ms","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-20T02:04:41.964348Z","caller":"traceutil/trace.go:171","msg":"trace[133884365] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25942; }","duration":"339.955093ms","start":"2026-08-20T02:04:41.624378Z","end":"2026-08-20T02:04:41.964333Z","steps":["trace[133884365] 'agreement among raft nodes before linearized reading' (duration: 339.706276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:41.964392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:41.624359Z","time spent":"340.020585ms","remote":"127.0.0.1:53754","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-20T02:04:41.964286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.673892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:04:41.964566Z","caller":"traceutil/trace.go:171","msg":"trace[456061337] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:25942; }","duration":"256.990231ms","start":"2026-08-20T02:04:41.707553Z","end":"2026-08-20T02:04:41.964544Z","steps":["trace[456061337] 'agreement among raft nodes before linearized reading' (duration: 256.680982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:42.628442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.709961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:04:42.62854Z","caller":"traceutil/trace.go:171","msg":"trace[1014835384] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25943; }","duration":"126.883306ms","start":"2026-08-20T02:04:42.501636Z","end":"2026-08-20T02:04:42.628519Z","steps":["trace[1014835384] 'count revisions from in-memory index tree' (duration: 126.543187ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:42.839981Z","caller":"traceutil/trace.go:171","msg":"trace[1343608419] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"221.249595ms","start":"2026-08-20T02:04:42.618668Z","end":"2026-08-20T02:04:42.839918Z","steps":["trace[1343608419] 'process raft request' (duration: 194.393057ms)","trace[1343608419] 'compare' (duration: 26.677984ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:42.842613Z","caller":"traceutil/trace.go:171","msg":"trace[1231647706] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"209.873278ms","start":"2026-08-20T02:04:42.632716Z","end":"2026-08-20T02:04:42.842589Z","steps":["trace[1231647706] 'process raft request' (duration: 209.714194ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:42.842723Z","caller":"traceutil/trace.go:171","msg":"trace[1436851888] linearizableReadLoop","detail":"{readStateIndex:26779; appliedIndex:26776; }","duration":"190.101487ms","start":"2026-08-20T02:04:42.652593Z","end":"2026-08-20T02:04:42.842695Z","steps":["trace[1436851888] 'read index received' (duration: 160.561494ms)","trace[1436851888] 'applied index is now lower than readState.Index' (duration: 29.538083ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:42.842818Z","caller":"traceutil/trace.go:171","msg":"trace[399152217] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"209.658732ms","start":"2026-08-20T02:04:42.633124Z","end":"2026-08-20T02:04:42.842783Z","steps":["trace[399152217] 'process raft request' (duration: 209.426586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:42.842943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.635978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:04:42.843Z","caller":"traceutil/trace.go:171","msg":"trace[1157885323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25947; }","duration":"145.757582ms","start":"2026-08-20T02:04:42.697225Z","end":"2026-08-20T02:04:42.842982Z","steps":["trace[1157885323] 'agreement among raft nodes before linearized reading' (duration: 145.627448ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:42.843015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.400775ms","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-20T02:04:42.843081Z","caller":"traceutil/trace.go:171","msg":"trace[1573470827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25947; }","duration":"190.517159ms","start":"2026-08-20T02:04:42.652548Z","end":"2026-08-20T02:04:42.843065Z","steps":["trace[1573470827] 'agreement among raft nodes before linearized reading' (duration: 190.283623ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:44.089689Z","caller":"traceutil/trace.go:171","msg":"trace[2037183354] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"116.319881ms","start":"2026-08-20T02:04:43.973343Z","end":"2026-08-20T02:04:44.089662Z","steps":["trace[2037183354] 'process raft request' (duration: 116.149697ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:44.108633Z","caller":"traceutil/trace.go:171","msg":"trace[418587036] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"135.252319ms","start":"2026-08-20T02:04:43.973357Z","end":"2026-08-20T02:04:44.10861Z","steps":["trace[418587036] 'process raft request' (duration: 135.119526ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:44.108825Z","caller":"traceutil/trace.go:171","msg":"trace[835802148] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"132.585314ms","start":"2026-08-20T02:04:43.976169Z","end":"2026-08-20T02:04:44.108754Z","steps":["trace[835802148] 'process raft request' (duration: 132.394369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:46.465023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.440377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:04:46.465352Z","caller":"traceutil/trace.go:171","msg":"trace[2124881522] transaction","detail":"{read_only:false; response_revision:25973; number_of_response:1; }","duration":"273.054549ms","start":"2026-08-20T02:04:46.192243Z","end":"2026-08-20T02:04:46.465297Z","steps":["trace[2124881522] 'process raft request' (duration: 129.251272ms)","trace[2124881522] 'compare' (duration: 143.135798ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:46.465588Z","caller":"traceutil/trace.go:171","msg":"trace[1590593225] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"271.036173ms","start":"2026-08-20T02:04:46.194541Z","end":"2026-08-20T02:04:46.465577Z","steps":["trace[1590593225] 'process raft request' (duration: 270.58537ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:46.465653Z","caller":"traceutil/trace.go:171","msg":"trace[547152480] linearizableReadLoop","detail":"{readStateIndex:26807; appliedIndex:26805; }","duration":"144.069214ms","start":"2026-08-20T02:04:46.32156Z","end":"2026-08-20T02:04:46.465629Z","steps":["trace[547152480] 'read index received' (duration: 140.295309ms)","trace[547152480] 'applied index is now lower than readState.Index' (duration: 3.758635ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:46.46594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.370453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:46.465997Z","caller":"traceutil/trace.go:171","msg":"trace[666565660] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25975; }","duration":"144.489256ms","start":"2026-08-20T02:04:46.321491Z","end":"2026-08-20T02:04:46.46598Z","steps":["trace[666565660] 'agreement among raft nodes before linearized reading' (duration: 144.28389ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:05:00.294692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22540} {"level":"info","ts":"2026-08-20T02:05:00.405553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22540,"took":"106.003814ms","hash":93641800,"current-db-size-bytes":57544704,"current-db-size":"58 MB","current-db-size-in-use-bytes":46899200,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-08-20T02:05:00.405628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":93641800,"revision":22540,"compact-revision":20232} {"level":"info","ts":"2026-08-20T02:05:01.340022Z","caller":"traceutil/trace.go:171","msg":"trace[1460816055] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"127.39597ms","start":"2026-08-20T02:05:01.212604Z","end":"2026-08-20T02:05:01.34Z","steps":["trace[1460816055] 'process raft request' (duration: 127.293547ms)"],"step_count":1}