{"level":"warn","ts":"2026-05-09T21:43:20.498981Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:43:20.499087Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-05-09T21:43:20.499224Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:43:20.499248Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-09T21:43:20.499294Z","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-05-09T21:43:20.50016Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-05-09T21:43:20.500285Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-05-09T21:43:20.503774Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.091019ms"} {"level":"info","ts":"2026-05-09T21:43:20.513779Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-05-09T21:43:20.513871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T21:43:20.513909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-05-09T21:43:20.513938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T21:43:20.513956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-05-09T21:43:20.514029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-05-09T21:43:20.520006Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T21:43:20.521352Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T21:43:20.522658Z","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-05-09T21:43:20.524738Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T21:43:20.524852Z","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-05-09T21:43:20.524962Z","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-05-09T21:43:20.524973Z","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-05-09T21:43:20.525325Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T21:43:20.526941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-05-09T21:43:20.527242Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-09T21:43:20.529281Z","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-05-09T21:43:20.529382Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-09T21:43:20.529461Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-09T21:43:20.529497Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T21:43:20.529528Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T21:43:20.715005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T21:43:20.715117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T21:43:20.715157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-05-09T21:43:20.715189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-05-09T21:43:20.71521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-09T21:43:20.715234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-05-09T21:43:20.715264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-09T21:43:20.716265Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:43:20.717123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:43:20.717126Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T21:43:20.717167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:43:20.717454Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T21:43:20.717526Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T21:43:20.719339Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-05-09T21:43:20.719626Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:43:20.719722Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:43:20.719771Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:43:20.722237Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T21:43:42.969894Z","caller":"traceutil/trace.go:171","msg":"trace[650567731] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"142.034731ms","start":"2026-05-09T21:43:42.827843Z","end":"2026-05-09T21:43:42.969877Z","steps":["trace[650567731] 'process raft request' (duration: 141.905448ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:48.466952Z","caller":"traceutil/trace.go:171","msg":"trace[2057666686] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"126.247828ms","start":"2026-05-09T21:43:48.340679Z","end":"2026-05-09T21:43:48.466927Z","steps":["trace[2057666686] 'read index received' (duration: 126.105254ms)","trace[2057666686] 'applied index is now lower than readState.Index' (duration: 141.634µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:43:48.467007Z","caller":"traceutil/trace.go:171","msg":"trace[503857460] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"126.504804ms","start":"2026-05-09T21:43:48.340473Z","end":"2026-05-09T21:43:48.466978Z","steps":["trace[503857460] 'process raft request' (duration: 126.318109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:43:48.467113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.394571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:43:48.46717Z","caller":"traceutil/trace.go:171","msg":"trace[511953340] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:463; }","duration":"126.494324ms","start":"2026-05-09T21:43:48.340663Z","end":"2026-05-09T21:43:48.467157Z","steps":["trace[511953340] 'agreement among raft nodes before linearized reading' (duration: 126.386342ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:52.683747Z","caller":"traceutil/trace.go:171","msg":"trace[157190953] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"203.061856ms","start":"2026-05-09T21:43:52.480657Z","end":"2026-05-09T21:43:52.683719Z","steps":["trace[157190953] 'process raft request' (duration: 202.847071ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:52.712869Z","caller":"traceutil/trace.go:171","msg":"trace[1914089166] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"199.553256ms","start":"2026-05-09T21:43:52.513292Z","end":"2026-05-09T21:43:52.712845Z","steps":["trace[1914089166] 'process raft request' (duration: 198.947861ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:54.746136Z","caller":"traceutil/trace.go:171","msg":"trace[1049471106] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"177.068883ms","start":"2026-05-09T21:43:54.569025Z","end":"2026-05-09T21:43:54.746094Z","steps":["trace[1049471106] 'process raft request' (duration: 176.378996ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:55.230694Z","caller":"traceutil/trace.go:171","msg":"trace[11712526] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"429.396385ms","start":"2026-05-09T21:43:54.801261Z","end":"2026-05-09T21:43:55.230657Z","steps":["trace[11712526] 'process raft request' (duration: 371.401627ms)","trace[11712526] 'compare' (duration: 57.738672ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:43:55.230954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.724029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T21:43:55.231007Z","caller":"traceutil/trace.go:171","msg":"trace[245760231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"215.827942ms","start":"2026-05-09T21:43:55.015167Z","end":"2026-05-09T21:43:55.230995Z","steps":["trace[245760231] 'agreement among raft nodes before linearized reading' (duration: 215.640977ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:43:55.230752Z","caller":"traceutil/trace.go:171","msg":"trace[862624371] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"215.312968ms","start":"2026-05-09T21:43:55.015225Z","end":"2026-05-09T21:43:55.230538Z","steps":["trace[862624371] 'read index received' (duration: 157.428252ms)","trace[862624371] 'applied index is now lower than readState.Index' (duration: 57.883636ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:43:55.231294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:43:54.80124Z","time spent":"429.541589ms","remote":"127.0.0.1:41430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T21:43:55.231461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.254839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T21:43:55.2315Z","caller":"traceutil/trace.go:171","msg":"trace[1236139900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"137.32757ms","start":"2026-05-09T21:43:55.094162Z","end":"2026-05-09T21:43:55.23149Z","steps":["trace[1236139900] 'agreement among raft nodes before linearized reading' (duration: 137.232378ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:44:11.471848Z","caller":"traceutil/trace.go:171","msg":"trace[1191416645] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"152.944198ms","start":"2026-05-09T21:44:11.318877Z","end":"2026-05-09T21:44:11.471821Z","steps":["trace[1191416645] 'read index received' (duration: 152.773954ms)","trace[1191416645] 'applied index is now lower than readState.Index' (duration: 169.104µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:44:11.472134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.231915ms","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-05-09T21:44:11.472149Z","caller":"traceutil/trace.go:171","msg":"trace[836649138] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"154.400025ms","start":"2026-05-09T21:44:11.317726Z","end":"2026-05-09T21:44:11.472126Z","steps":["trace[836649138] 'process raft request' (duration: 153.950404ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:44:11.472191Z","caller":"traceutil/trace.go:171","msg":"trace[2132913957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"153.315198ms","start":"2026-05-09T21:44:11.318853Z","end":"2026-05-09T21:44:11.472169Z","steps":["trace[2132913957] 'agreement among raft nodes before linearized reading' (duration: 153.076862ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:44:42.167399Z","caller":"traceutil/trace.go:171","msg":"trace[2047116280] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"134.925167ms","start":"2026-05-09T21:44:42.032432Z","end":"2026-05-09T21:44:42.167358Z","steps":["trace[2047116280] 'process raft request' (duration: 134.701271ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:23.638347Z","caller":"traceutil/trace.go:171","msg":"trace[1559925904] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"180.325406ms","start":"2026-05-09T21:45:23.457975Z","end":"2026-05-09T21:45:23.6383Z","steps":["trace[1559925904] 'process raft request' (duration: 155.26215ms)","trace[1559925904] 'compare' (duration: 24.795599ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:24.010819Z","caller":"traceutil/trace.go:171","msg":"trace[1031972524] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"174.35093ms","start":"2026-05-09T21:45:23.836439Z","end":"2026-05-09T21:45:24.01079Z","steps":["trace[1031972524] 'process raft request' (duration: 163.460222ms)","trace[1031972524] 'compare' (duration: 10.539729ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:24.010999Z","caller":"traceutil/trace.go:171","msg":"trace[1257879394] linearizableReadLoop","detail":"{readStateIndex:1853; appliedIndex:1850; }","duration":"173.623911ms","start":"2026-05-09T21:45:23.837365Z","end":"2026-05-09T21:45:24.010989Z","steps":["trace[1257879394] 'read index received' (duration: 5.338821ms)","trace[1257879394] 'applied index is now lower than readState.Index' (duration: 168.28402ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:24.011142Z","caller":"traceutil/trace.go:171","msg":"trace[1920501986] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"173.96409ms","start":"2026-05-09T21:45:23.837166Z","end":"2026-05-09T21:45:24.01113Z","steps":["trace[1920501986] 'process raft request' (duration: 173.58257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:24.011278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.8974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98405a29-76c9-45b8-898b-446db8278803\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-09T21:45:24.011363Z","caller":"traceutil/trace.go:171","msg":"trace[670542470] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-98405a29-76c9-45b8-898b-446db8278803; range_end:; response_count:1; response_revision:1786; }","duration":"174.007762ms","start":"2026-05-09T21:45:23.837334Z","end":"2026-05-09T21:45:24.011342Z","steps":["trace[670542470] 'agreement among raft nodes before linearized reading' (duration: 173.823906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:24.026941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.707305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-09T21:45:24.02701Z","caller":"traceutil/trace.go:171","msg":"trace[1294472545] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1787; }","duration":"182.780957ms","start":"2026-05-09T21:45:23.844214Z","end":"2026-05-09T21:45:24.026995Z","steps":["trace[1294472545] 'agreement among raft nodes before linearized reading' (duration: 182.631693ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:31.793699Z","caller":"traceutil/trace.go:171","msg":"trace[1549110833] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"108.483333ms","start":"2026-05-09T21:45:31.685175Z","end":"2026-05-09T21:45:31.793658Z","steps":["trace[1549110833] 'process raft request' (duration: 108.260327ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:45:35.932976Z","caller":"traceutil/trace.go:171","msg":"trace[600783536] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"126.882184ms","start":"2026-05-09T21:45:35.806068Z","end":"2026-05-09T21:45:35.93295Z","steps":["trace[600783536] 'read index received' (duration: 126.739081ms)","trace[600783536] 'applied index is now lower than readState.Index' (duration: 142.123µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:45:35.933047Z","caller":"traceutil/trace.go:171","msg":"trace[1454330036] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"150.036803ms","start":"2026-05-09T21:45:35.782972Z","end":"2026-05-09T21:45:35.933009Z","steps":["trace[1454330036] 'process raft request' (duration: 149.842078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:45:35.933162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.072009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-09T21:45:35.93321Z","caller":"traceutil/trace.go:171","msg":"trace[1151123888] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1893; }","duration":"127.168971ms","start":"2026-05-09T21:45:35.806028Z","end":"2026-05-09T21:45:35.933197Z","steps":["trace[1151123888] 'agreement among raft nodes before linearized reading' (duration: 127.022438ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:08.321644Z","caller":"traceutil/trace.go:171","msg":"trace[1274481364] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"234.314121ms","start":"2026-05-09T21:46:08.087305Z","end":"2026-05-09T21:46:08.32162Z","steps":["trace[1274481364] 'process raft request' (duration: 234.178708ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:08.431971Z","caller":"traceutil/trace.go:171","msg":"trace[439945907] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"344.155968ms","start":"2026-05-09T21:46:08.087786Z","end":"2026-05-09T21:46:08.431942Z","steps":["trace[439945907] 'process raft request' (duration: 278.093037ms)","trace[439945907] 'compare' (duration: 65.886186ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:46:08.432306Z","caller":"traceutil/trace.go:171","msg":"trace[435335140] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2156; }","duration":"285.448776ms","start":"2026-05-09T21:46:08.146835Z","end":"2026-05-09T21:46:08.432284Z","steps":["trace[435335140] 'read index received' (duration: 174.598256ms)","trace[435335140] 'applied index is now lower than readState.Index' (duration: 110.84893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:46:08.432441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.58556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:46:08.432332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:46:08.087771Z","time spent":"344.28487ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:46:08.432509Z","caller":"traceutil/trace.go:171","msg":"trace[1226644713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2083; }","duration":"285.701832ms","start":"2026-05-09T21:46:08.146792Z","end":"2026-05-09T21:46:08.432494Z","steps":["trace[1226644713] 'agreement among raft nodes before linearized reading' (duration: 285.571099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:08.432747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.006551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-09T21:46:08.4328Z","caller":"traceutil/trace.go:171","msg":"trace[1289661211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2083; }","duration":"220.086832ms","start":"2026-05-09T21:46:08.212702Z","end":"2026-05-09T21:46:08.432789Z","steps":["trace[1289661211] 'agreement among raft nodes before linearized reading' (duration: 219.939637ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:08.432367Z","caller":"traceutil/trace.go:171","msg":"trace[327846133] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"273.490062ms","start":"2026-05-09T21:46:08.158841Z","end":"2026-05-09T21:46:08.432331Z","steps":["trace[327846133] 'process raft request' (duration: 273.051471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:08.432895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.588614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-09T21:46:08.432949Z","caller":"traceutil/trace.go:171","msg":"trace[1885474218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2083; }","duration":"222.665206ms","start":"2026-05-09T21:46:08.210263Z","end":"2026-05-09T21:46:08.432928Z","steps":["trace[1885474218] 'agreement among raft nodes before linearized reading' (duration: 222.525423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:46:08.432734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.398731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T21:46:08.432976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.8534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-09T21:46:08.433008Z","caller":"traceutil/trace.go:171","msg":"trace[1906254255] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2083; }","duration":"101.904831ms","start":"2026-05-09T21:46:08.331094Z","end":"2026-05-09T21:46:08.432999Z","steps":["trace[1906254255] 'agreement among raft nodes before linearized reading' (duration: 101.817819ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:08.43302Z","caller":"traceutil/trace.go:171","msg":"trace[323794659] 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:2083; }","duration":"273.715198ms","start":"2026-05-09T21:46:08.159281Z","end":"2026-05-09T21:46:08.432996Z","steps":["trace[323794659] 'agreement among raft nodes before linearized reading' (duration: 273.365769ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:46:13.701874Z","caller":"traceutil/trace.go:171","msg":"trace[825360865] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"112.142363ms","start":"2026-05-09T21:46:13.589699Z","end":"2026-05-09T21:46:13.701842Z","steps":["trace[825360865] 'process raft request' (duration: 59.804159ms)","trace[825360865] 'compare' (duration: 52.217701ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:31.135052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.953225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:47:31.135251Z","caller":"traceutil/trace.go:171","msg":"trace[702277823] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"285.503757ms","start":"2026-05-09T21:47:30.849729Z","end":"2026-05-09T21:47:31.135233Z","steps":["trace[702277823] 'process raft request' (duration: 285.437646ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:31.135354Z","caller":"traceutil/trace.go:171","msg":"trace[2050030507] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"316.602528ms","start":"2026-05-09T21:47:30.818699Z","end":"2026-05-09T21:47:31.135301Z","steps":["trace[2050030507] 'process raft request' (duration: 198.111759ms)","trace[2050030507] 'compare' (duration: 117.776681ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:31.135568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:30.818681Z","time spent":"316.789682ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T21:47:31.135566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.131091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-05-09T21:47:31.135766Z","caller":"traceutil/trace.go:171","msg":"trace[960086337] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2582; }","duration":"305.337807ms","start":"2026-05-09T21:47:30.830361Z","end":"2026-05-09T21:47:31.135698Z","steps":["trace[960086337] 'agreement among raft nodes before linearized reading' (duration: 305.026539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:31.135821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:30.830348Z","time spent":"305.460639ms","remote":"127.0.0.1:48106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-09T21:47:31.135794Z","caller":"traceutil/trace.go:171","msg":"trace[1062679468] linearizableReadLoop","detail":"{readStateIndex:2675; appliedIndex:2674; }","duration":"304.858525ms","start":"2026-05-09T21:47:30.830393Z","end":"2026-05-09T21:47:31.135251Z","steps":["trace[1062679468] 'read index received' (duration: 186.430877ms)","trace[1062679468] 'applied index is now lower than readState.Index' (duration: 118.425498ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:31.136185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.685149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T21:47:31.136227Z","caller":"traceutil/trace.go:171","msg":"trace[1685777186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2582; }","duration":"158.768071ms","start":"2026-05-09T21:47:30.977448Z","end":"2026-05-09T21:47:31.136216Z","steps":["trace[1685777186] 'agreement among raft nodes before linearized reading' (duration: 158.630458ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:07.452417Z","caller":"traceutil/trace.go:171","msg":"trace[1604832669] linearizableReadLoop","detail":"{readStateIndex:3726; appliedIndex:3725; }","duration":"107.915256ms","start":"2026-05-09T21:50:07.344478Z","end":"2026-05-09T21:50:07.452394Z","steps":["trace[1604832669] 'read index received' (duration: 107.693251ms)","trace[1604832669] 'applied index is now lower than readState.Index' (duration: 221.025µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:50:07.452602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.10819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T21:50:07.452565Z","caller":"traceutil/trace.go:171","msg":"trace[1987241064] transaction","detail":"{read_only:false; response_revision:3593; number_of_response:1; }","duration":"120.419847ms","start":"2026-05-09T21:50:07.332079Z","end":"2026-05-09T21:50:07.452499Z","steps":["trace[1987241064] 'process raft request' (duration: 120.164911ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:07.452656Z","caller":"traceutil/trace.go:171","msg":"trace[1458415531] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:3593; }","duration":"108.211223ms","start":"2026-05-09T21:50:07.344427Z","end":"2026-05-09T21:50:07.452638Z","steps":["trace[1458415531] 'agreement among raft nodes before linearized reading' (duration: 108.08261ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:41.924175Z","caller":"traceutil/trace.go:171","msg":"trace[1765298694] transaction","detail":"{read_only:false; response_revision:5067; number_of_response:1; }","duration":"197.349346ms","start":"2026-05-09T21:51:41.726796Z","end":"2026-05-09T21:51:41.924145Z","steps":["trace[1765298694] 'process raft request' (duration: 131.251956ms)","trace[1765298694] 'compare' (duration: 65.889945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:51:41.924384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.714206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T21:51:41.924191Z","caller":"traceutil/trace.go:171","msg":"trace[1277566721] linearizableReadLoop","detail":"{readStateIndex:5253; appliedIndex:5252; }","duration":"159.500131ms","start":"2026-05-09T21:51:41.764658Z","end":"2026-05-09T21:51:41.924158Z","steps":["trace[1277566721] 'read index received' (duration: 93.35009ms)","trace[1277566721] 'applied index is now lower than readState.Index' (duration: 66.146951ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:41.924803Z","caller":"traceutil/trace.go:171","msg":"trace[1437960510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5068; }","duration":"160.165617ms","start":"2026-05-09T21:51:41.764617Z","end":"2026-05-09T21:51:41.924783Z","steps":["trace[1437960510] 'agreement among raft nodes before linearized reading' (duration: 159.602043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:42.321042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.621906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:51:42.321163Z","caller":"traceutil/trace.go:171","msg":"trace[749157037] transaction","detail":"{read_only:false; response_revision:5072; number_of_response:1; }","duration":"146.979158ms","start":"2026-05-09T21:51:42.174165Z","end":"2026-05-09T21:51:42.321144Z","steps":["trace[749157037] 'process raft request' (duration: 34.167728ms)","trace[749157037] 'compare' (duration: 112.3898ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:42.363883Z","caller":"traceutil/trace.go:171","msg":"trace[1016034719] transaction","detail":"{read_only:false; response_revision:5073; number_of_response:1; }","duration":"189.512357ms","start":"2026-05-09T21:51:42.174333Z","end":"2026-05-09T21:51:42.363845Z","steps":["trace[1016034719] 'process raft request' (duration: 189.283242ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:42.363899Z","caller":"traceutil/trace.go:171","msg":"trace[1912672239] linearizableReadLoop","detail":"{readStateIndex:5260; appliedIndex:5257; }","duration":"187.806555ms","start":"2026-05-09T21:51:42.176068Z","end":"2026-05-09T21:51:42.363874Z","steps":["trace[1912672239] 'read index received' (duration: 32.198188ms)","trace[1912672239] 'applied index is now lower than readState.Index' (duration: 155.606987ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:51:42.364375Z","caller":"traceutil/trace.go:171","msg":"trace[1738101603] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"189.385383ms","start":"2026-05-09T21:51:42.174945Z","end":"2026-05-09T21:51:42.36433Z","steps":["trace[1738101603] 'process raft request' (duration: 188.804149ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:51:42.364498Z","caller":"traceutil/trace.go:171","msg":"trace[373080936] transaction","detail":"{read_only:false; response_revision:5075; number_of_response:1; }","duration":"183.05614ms","start":"2026-05-09T21:51:42.181391Z","end":"2026-05-09T21:51:42.364447Z","steps":["trace[373080936] 'process raft request' (duration: 182.423775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:51:42.364745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.295707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-05-09T21:51:42.364829Z","caller":"traceutil/trace.go:171","msg":"trace[2059217069] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5075; }","duration":"188.768329ms","start":"2026-05-09T21:51:42.176045Z","end":"2026-05-09T21:51:42.364814Z","steps":["trace[2059217069] 'agreement among raft nodes before linearized reading' (duration: 187.910318ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:53:20.937613Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2841} {"level":"info","ts":"2026-05-09T21:53:21.004772Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2841,"took":"65.736835ms","hash":2139966579,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":33423360,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T21:53:21.004845Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2139966579,"revision":2841,"compact-revision":-1} {"level":"info","ts":"2026-05-09T21:57:20.677163Z","caller":"traceutil/trace.go:171","msg":"trace[1031945310] linearizableReadLoop","detail":"{readStateIndex:8065; appliedIndex:8064; }","duration":"152.834029ms","start":"2026-05-09T21:57:20.524306Z","end":"2026-05-09T21:57:20.67714Z","steps":["trace[1031945310] 'read index received' (duration: 152.649144ms)","trace[1031945310] 'applied index is now lower than readState.Index' (duration: 184.024µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:57:20.677236Z","caller":"traceutil/trace.go:171","msg":"trace[606626587] transaction","detail":"{read_only:false; response_revision:7797; number_of_response:1; }","duration":"238.833011ms","start":"2026-05-09T21:57:20.438367Z","end":"2026-05-09T21:57:20.6772Z","steps":["trace[606626587] 'process raft request' (duration: 238.634206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:57:20.677379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.050414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-5cd5cf76b5-lsf97.18ae047b7d3ed6f3\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-05-09T21:57:20.677448Z","caller":"traceutil/trace.go:171","msg":"trace[1798528184] range","detail":"{range_begin:/registry/events/openstack/barbican-api-5cd5cf76b5-lsf97.18ae047b7d3ed6f3; range_end:; response_count:1; response_revision:7797; }","duration":"153.153066ms","start":"2026-05-09T21:57:20.524266Z","end":"2026-05-09T21:57:20.677419Z","steps":["trace[1798528184] 'agreement among raft nodes before linearized reading' (duration: 152.978342ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:57:20.781849Z","caller":"traceutil/trace.go:171","msg":"trace[1993854365] transaction","detail":"{read_only:false; response_revision:7798; number_of_response:1; }","duration":"102.163652ms","start":"2026-05-09T21:57:20.679664Z","end":"2026-05-09T21:57:20.781828Z","steps":["trace[1993854365] 'process raft request' (duration: 98.988185ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:03.699178Z","caller":"traceutil/trace.go:171","msg":"trace[749728510] transaction","detail":"{read_only:false; response_revision:8104; number_of_response:1; }","duration":"420.950852ms","start":"2026-05-09T21:58:03.2782Z","end":"2026-05-09T21:58:03.699151Z","steps":["trace[749728510] 'process raft request' (duration: 420.754957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:58:03.699421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:58:03.278164Z","time spent":"421.120756ms","remote":"127.0.0.1:56092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7438,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:58:03.703254Z","caller":"traceutil/trace.go:171","msg":"trace[253006979] transaction","detail":"{read_only:false; response_revision:8105; number_of_response:1; }","duration":"380.511281ms","start":"2026-05-09T21:58:03.322717Z","end":"2026-05-09T21:58:03.703228Z","steps":["trace[253006979] 'process raft request' (duration: 378.966434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:58:03.703415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:58:03.322688Z","time spent":"380.647485ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:58:03.703267Z","caller":"traceutil/trace.go:171","msg":"trace[1317477833] linearizableReadLoop","detail":"{readStateIndex:8386; appliedIndex:8384; }","duration":"216.738991ms","start":"2026-05-09T21:58:03.486494Z","end":"2026-05-09T21:58:03.703233Z","steps":["trace[1317477833] 'read index received' (duration: 212.493099ms)","trace[1317477833] 'applied index is now lower than readState.Index' (duration: 4.244732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:58:03.704596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.086473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2369114"} {"level":"info","ts":"2026-05-09T21:58:03.70466Z","caller":"traceutil/trace.go:171","msg":"trace[1152162660] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8106; }","duration":"218.187326ms","start":"2026-05-09T21:58:03.486456Z","end":"2026-05-09T21:58:03.704643Z","steps":["trace[1152162660] 'agreement among raft nodes before linearized reading' (duration: 216.861144ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:14.826461Z","caller":"traceutil/trace.go:171","msg":"trace[1958354618] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"130.403308ms","start":"2026-05-09T21:58:14.696012Z","end":"2026-05-09T21:58:14.826415Z","steps":["trace[1958354618] 'process raft request' (duration: 125.693686ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:14.841115Z","caller":"traceutil/trace.go:171","msg":"trace[2004592096] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"127.046979ms","start":"2026-05-09T21:58:14.714034Z","end":"2026-05-09T21:58:14.841081Z","steps":["trace[2004592096] 'process raft request' (duration: 126.755621ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:15.218116Z","caller":"traceutil/trace.go:171","msg":"trace[561561520] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"128.294029ms","start":"2026-05-09T21:58:15.089787Z","end":"2026-05-09T21:58:15.218081Z","steps":["trace[561561520] 'process raft request' (duration: 128.136755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:58:16.317315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.435347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3173"} {"level":"info","ts":"2026-05-09T21:58:16.317404Z","caller":"traceutil/trace.go:171","msg":"trace[1821263487] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8189; }","duration":"204.57173ms","start":"2026-05-09T21:58:16.112813Z","end":"2026-05-09T21:58:16.317385Z","steps":["trace[1821263487] 'range keys from in-memory index tree' (duration: 204.259912ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:58:20.943808Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5994} {"level":"info","ts":"2026-05-09T21:58:21.04377Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5994,"took":"97.822194ms","hash":3962933927,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":22482944,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-09T21:58:21.043843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3962933927,"revision":5994,"compact-revision":2841} {"level":"info","ts":"2026-05-09T21:59:34.128573Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241d.wal"} {"level":"info","ts":"2026-05-09T22:01:04.8638Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:01:04.87206Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T22:01:04.872186Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-09T22:02:57.953405Z","caller":"traceutil/trace.go:171","msg":"trace[623310053] transaction","detail":"{read_only:false; response_revision:10722; number_of_response:1; }","duration":"112.139211ms","start":"2026-05-09T22:02:57.841225Z","end":"2026-05-09T22:02:57.953364Z","steps":["trace[623310053] 'process raft request' (duration: 99.154123ms)","trace[623310053] 'compare' (duration: 12.817324ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:02:58.177893Z","caller":"traceutil/trace.go:171","msg":"trace[2106030251] transaction","detail":"{read_only:false; response_revision:10723; number_of_response:1; }","duration":"219.162071ms","start":"2026-05-09T22:02:57.958699Z","end":"2026-05-09T22:02:58.177861Z","steps":["trace[2106030251] 'process raft request' (duration: 130.465598ms)","trace[2106030251] 'compare' (duration: 88.457667ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:02:58.188996Z","caller":"traceutil/trace.go:171","msg":"trace[429092088] transaction","detail":"{read_only:false; response_revision:10724; number_of_response:1; }","duration":"230.215595ms","start":"2026-05-09T22:02:57.95876Z","end":"2026-05-09T22:02:58.188976Z","steps":["trace[429092088] 'process raft request' (duration: 230.02402ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:03:20.951076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8239} {"level":"info","ts":"2026-05-09T22:03:21.028975Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8239,"took":"75.772215ms","hash":3225848142,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":26890240,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-09T22:03:21.029058Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3225848142,"revision":8239,"compact-revision":5994} {"level":"info","ts":"2026-05-09T22:08:20.957846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10895} {"level":"info","ts":"2026-05-09T22:08:21.042212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10895,"took":"81.973552ms","hash":2716179788,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":27521024,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T22:08:21.042287Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2716179788,"revision":10895,"compact-revision":8239} {"level":"warn","ts":"2026-05-09T22:09:00.023435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.326275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55wd7\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-09T22:09:00.023501Z","caller":"traceutil/trace.go:171","msg":"trace[458790707] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55wd7; range_end:; response_count:1; response_revision:13211; }","duration":"183.416178ms","start":"2026-05-09T22:08:59.840072Z","end":"2026-05-09T22:09:00.023488Z","steps":["trace[458790707] 'range keys from in-memory index tree' (duration: 183.208273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:09:13.97377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.849913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T22:09:13.973873Z","caller":"traceutil/trace.go:171","msg":"trace[521851289] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:13300; }","duration":"187.986538ms","start":"2026-05-09T22:09:13.785867Z","end":"2026-05-09T22:09:13.973853Z","steps":["trace[521851289] 'count revisions from in-memory index tree' (duration: 187.751771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:09:13.974115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.426996ms","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-05-09T22:09:13.974183Z","caller":"traceutil/trace.go:171","msg":"trace[1062813249] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13300; }","duration":"159.541049ms","start":"2026-05-09T22:09:13.814627Z","end":"2026-05-09T22:09:13.974168Z","steps":["trace[1062813249] 'range keys from in-memory index tree' (duration: 159.297613ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:09:14.011976Z","caller":"traceutil/trace.go:171","msg":"trace[1478833194] transaction","detail":"{read_only:false; response_revision:13301; number_of_response:1; }","duration":"108.235638ms","start":"2026-05-09T22:09:13.903715Z","end":"2026-05-09T22:09:14.01195Z","steps":["trace[1478833194] 'process raft request' (duration: 108.075284ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:09:14.175887Z","caller":"traceutil/trace.go:171","msg":"trace[482983091] linearizableReadLoop","detail":"{readStateIndex:13750; appliedIndex:13747; }","duration":"194.338538ms","start":"2026-05-09T22:09:13.981525Z","end":"2026-05-09T22:09:14.175863Z","steps":["trace[482983091] 'read index received' (duration: 30.250511ms)","trace[482983091] 'applied index is now lower than readState.Index' (duration: 164.086837ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:09:14.175877Z","caller":"traceutil/trace.go:171","msg":"trace[1464554956] transaction","detail":"{read_only:false; response_revision:13302; number_of_response:1; }","duration":"197.156475ms","start":"2026-05-09T22:09:13.978689Z","end":"2026-05-09T22:09:14.175846Z","steps":["trace[1464554956] 'process raft request' (duration: 196.758475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:09:14.176054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.511782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:09:14.17611Z","caller":"traceutil/trace.go:171","msg":"trace[319632430] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:13303; }","duration":"194.609355ms","start":"2026-05-09T22:09:13.981489Z","end":"2026-05-09T22:09:14.176098Z","steps":["trace[319632430] 'agreement among raft nodes before linearized reading' (duration: 194.468592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:09:14.176058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.66552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:09:14.176196Z","caller":"traceutil/trace.go:171","msg":"trace[1648872531] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:13303; }","duration":"182.803013ms","start":"2026-05-09T22:09:13.993365Z","end":"2026-05-09T22:09:14.176168Z","steps":["trace[1648872531] 'agreement among raft nodes before linearized reading' (duration: 182.64307ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:09:22.302575Z","caller":"traceutil/trace.go:171","msg":"trace[1493610082] transaction","detail":"{read_only:false; response_revision:13456; number_of_response:1; }","duration":"100.609736ms","start":"2026-05-09T22:09:22.201939Z","end":"2026-05-09T22:09:22.302548Z","steps":["trace[1493610082] 'process raft request' (duration: 98.322321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:09:22.592855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.667009ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T22:09:22.593172Z","caller":"traceutil/trace.go:171","msg":"trace[786128848] transaction","detail":"{read_only:false; response_revision:13458; number_of_response:1; }","duration":"285.716043ms","start":"2026-05-09T22:09:22.307422Z","end":"2026-05-09T22:09:22.593138Z","steps":["trace[786128848] 'process raft request' (duration: 285.551339ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:09:22.593186Z","caller":"traceutil/trace.go:171","msg":"trace[1009410961] linearizableReadLoop","detail":"{readStateIndex:13909; appliedIndex:13907; }","duration":"285.326434ms","start":"2026-05-09T22:09:22.307837Z","end":"2026-05-09T22:09:22.593164Z","steps":["trace[1009410961] 'read index received' (duration: 131.257045ms)","trace[1009410961] 'applied index is now lower than readState.Index' (duration: 154.068099ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:09:22.593328Z","caller":"traceutil/trace.go:171","msg":"trace[136159496] transaction","detail":"{read_only:false; response_revision:13459; number_of_response:1; }","duration":"285.441647ms","start":"2026-05-09T22:09:22.307843Z","end":"2026-05-09T22:09:22.593285Z","steps":["trace[136159496] 'process raft request' (duration: 285.263213ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:09:22.593172Z","caller":"traceutil/trace.go:171","msg":"trace[2122097716] transaction","detail":"{read_only:false; response_revision:13457; number_of_response:1; }","duration":"285.710703ms","start":"2026-05-09T22:09:22.307422Z","end":"2026-05-09T22:09:22.593133Z","steps":["trace[2122097716] 'process raft request' (duration: 131.658855ms)","trace[2122097716] 'compare' (duration: 153.365842ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:09:22.593587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.109072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T22:09:22.593684Z","caller":"traceutil/trace.go:171","msg":"trace[2147288027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13459; }","duration":"152.253837ms","start":"2026-05-09T22:09:22.441407Z","end":"2026-05-09T22:09:22.59366Z","steps":["trace[2147288027] 'agreement among raft nodes before linearized reading' (duration: 151.99962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:09:22.593909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.053332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:67 size:619693"} {"level":"info","ts":"2026-05-09T22:09:22.593961Z","caller":"traceutil/trace.go:171","msg":"trace[511922894] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:67; response_revision:13459; }","duration":"286.139724ms","start":"2026-05-09T22:09:22.307806Z","end":"2026-05-09T22:09:22.593946Z","steps":["trace[511922894] 'agreement among raft nodes before linearized reading' (duration: 285.425897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:10:55.00972Z","caller":"traceutil/trace.go:171","msg":"trace[654905275] transaction","detail":"{read_only:false; response_revision:14504; number_of_response:1; }","duration":"104.388346ms","start":"2026-05-09T22:10:54.905275Z","end":"2026-05-09T22:10:55.009664Z","steps":["trace[654905275] 'process raft request' (duration: 104.14756ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:13:20.971381Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12963} {"level":"info","ts":"2026-05-09T22:13:21.050729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12963,"took":"77.140139ms","hash":3406629419,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":31358976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T22:13:21.050819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3406629419,"revision":12963,"compact-revision":10895} {"level":"info","ts":"2026-05-09T22:14:42.219338Z","caller":"traceutil/trace.go:171","msg":"trace[1260201817] transaction","detail":"{read_only:false; response_revision:16282; number_of_response:1; }","duration":"102.89419ms","start":"2026-05-09T22:14:42.115873Z","end":"2026-05-09T22:14:42.218767Z","steps":["trace[1260201817] 'process raft request' (duration: 38.080787ms)","trace[1260201817] 'compare' (duration: 64.612238ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:14:42.756207Z","caller":"traceutil/trace.go:171","msg":"trace[315330176] linearizableReadLoop","detail":"{readStateIndex:16824; appliedIndex:16823; }","duration":"369.276705ms","start":"2026-05-09T22:14:42.386912Z","end":"2026-05-09T22:14:42.756188Z","steps":["trace[315330176] 'read index received' (duration: 369.03141ms)","trace[315330176] 'applied index is now lower than readState.Index' (duration: 244.555µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:14:42.757083Z","caller":"traceutil/trace.go:171","msg":"trace[1553895256] transaction","detail":"{read_only:false; response_revision:16285; number_of_response:1; }","duration":"463.587061ms","start":"2026-05-09T22:14:42.293487Z","end":"2026-05-09T22:14:42.757074Z","steps":["trace[1553895256] 'process raft request' (duration: 462.529976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:14:42.757156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:14:42.293467Z","time spent":"463.634222ms","remote":"127.0.0.1:41060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15078,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:14:42.757396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.493185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1861"} {"level":"info","ts":"2026-05-09T22:14:42.757423Z","caller":"traceutil/trace.go:171","msg":"trace[1036028916] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:16285; }","duration":"370.559036ms","start":"2026-05-09T22:14:42.386856Z","end":"2026-05-09T22:14:42.757415Z","steps":["trace[1036028916] 'agreement among raft nodes before linearized reading' (duration: 370.452464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:14:42.757439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:14:42.386832Z","time spent":"370.602838ms","remote":"127.0.0.1:41032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1884,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"info","ts":"2026-05-09T22:18:20.977688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15603} {"level":"info","ts":"2026-05-09T22:18:21.075583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15603,"took":"94.308757ms","hash":1393145060,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":33488896,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T22:18:21.075662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1393145060,"revision":15603,"compact-revision":12963} {"level":"info","ts":"2026-05-09T22:20:41.033635Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:20:41.039736Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T22:20:41.03982Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T22:23:11.895796Z","caller":"traceutil/trace.go:171","msg":"trace[695866588] transaction","detail":"{read_only:false; response_revision:20427; number_of_response:1; }","duration":"138.220562ms","start":"2026-05-09T22:23:11.757542Z","end":"2026-05-09T22:23:11.895762Z","steps":["trace[695866588] 'process raft request' (duration: 137.856374ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:23:11.902156Z","caller":"traceutil/trace.go:171","msg":"trace[1905376985] transaction","detail":"{read_only:false; response_revision:20428; number_of_response:1; }","duration":"143.345636ms","start":"2026-05-09T22:23:11.758784Z","end":"2026-05-09T22:23:11.90213Z","steps":["trace[1905376985] 'process raft request' (duration: 141.85638ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:23:17.771741Z","caller":"traceutil/trace.go:171","msg":"trace[1229885006] transaction","detail":"{read_only:false; response_revision:20456; number_of_response:1; }","duration":"206.465568ms","start":"2026-05-09T22:23:17.56525Z","end":"2026-05-09T22:23:17.771716Z","steps":["trace[1229885006] 'process raft request' (duration: 140.357283ms)","trace[1229885006] 'compare' (duration: 65.91318ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:23:17.771876Z","caller":"traceutil/trace.go:171","msg":"trace[2070969979] transaction","detail":"{read_only:false; response_revision:20457; number_of_response:1; }","duration":"193.013287ms","start":"2026-05-09T22:23:17.578841Z","end":"2026-05-09T22:23:17.771854Z","steps":["trace[2070969979] 'process raft request' (duration: 192.819373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:23:18.197683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.024634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:23:18.197948Z","caller":"traceutil/trace.go:171","msg":"trace[668977505] transaction","detail":"{read_only:false; response_revision:20459; number_of_response:1; }","duration":"204.06481ms","start":"2026-05-09T22:23:17.993857Z","end":"2026-05-09T22:23:18.197922Z","steps":["trace[668977505] 'process raft request' (duration: 95.679299ms)","trace[668977505] 'compare' (duration: 107.89086ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:23:18.197968Z","caller":"traceutil/trace.go:171","msg":"trace[1209047862] transaction","detail":"{read_only:false; response_revision:20460; number_of_response:1; }","duration":"197.406412ms","start":"2026-05-09T22:23:18.000524Z","end":"2026-05-09T22:23:18.197931Z","steps":["trace[1209047862] 'process raft request' (duration: 197.254278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:23:18.479833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.950363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:23:18.48007Z","caller":"traceutil/trace.go:171","msg":"trace[1514549529] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:20460; }","duration":"166.110957ms","start":"2026-05-09T22:23:18.313801Z","end":"2026-05-09T22:23:18.479912Z","steps":["trace[1514549529] 'count revisions from in-memory index tree' (duration: 165.858442ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:23:20.985433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18224} {"level":"info","ts":"2026-05-09T22:23:21.113223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18224,"took":"124.198468ms","hash":749186670,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":30507008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T22:23:21.113272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":749186670,"revision":18224,"compact-revision":15603} {"level":"info","ts":"2026-05-09T22:26:49.492113Z","caller":"traceutil/trace.go:171","msg":"trace[821305821] linearizableReadLoop","detail":"{readStateIndex:23525; appliedIndex:23524; }","duration":"211.441166ms","start":"2026-05-09T22:26:49.280643Z","end":"2026-05-09T22:26:49.492084Z","steps":["trace[821305821] 'read index received' (duration: 211.236261ms)","trace[821305821] 'applied index is now lower than readState.Index' (duration: 203.755µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:26:49.492194Z","caller":"traceutil/trace.go:171","msg":"trace[1571815640] transaction","detail":"{read_only:false; response_revision:22785; number_of_response:1; }","duration":"212.962022ms","start":"2026-05-09T22:26:49.279197Z","end":"2026-05-09T22:26:49.492159Z","steps":["trace[1571815640] 'process raft request' (duration: 212.693356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:26:49.492397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.669721ms","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-05-09T22:26:49.492504Z","caller":"traceutil/trace.go:171","msg":"trace[1975865417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22785; }","duration":"211.867536ms","start":"2026-05-09T22:26:49.28062Z","end":"2026-05-09T22:26:49.492487Z","steps":["trace[1975865417] 'agreement among raft nodes before linearized reading' (duration: 211.584669ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:26:49.62081Z","caller":"traceutil/trace.go:171","msg":"trace[649246201] transaction","detail":"{read_only:false; response_revision:22786; number_of_response:1; }","duration":"124.010663ms","start":"2026-05-09T22:26:49.496772Z","end":"2026-05-09T22:26:49.620783Z","steps":["trace[649246201] 'process raft request' (duration: 96.005766ms)","trace[649246201] 'compare' (duration: 27.869464ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:26:49.622871Z","caller":"traceutil/trace.go:171","msg":"trace[966063233] transaction","detail":"{read_only:false; response_revision:22787; number_of_response:1; }","duration":"101.844617ms","start":"2026-05-09T22:26:49.520972Z","end":"2026-05-09T22:26:49.622816Z","steps":["trace[966063233] 'process raft request' (duration: 101.606491ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:28:20.99257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20475} {"level":"info","ts":"2026-05-09T22:28:21.098915Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20475,"took":"102.169454ms","hash":4250551901,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":42151936,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-09T22:28:21.098972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4250551901,"revision":20475,"compact-revision":18224} {"level":"warn","ts":"2026-05-09T22:30:04.42989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.091338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:30:04.430001Z","caller":"traceutil/trace.go:171","msg":"trace[944451357] transaction","detail":"{read_only:false; response_revision:24807; number_of_response:1; }","duration":"350.760385ms","start":"2026-05-09T22:30:04.079224Z","end":"2026-05-09T22:30:04.429985Z","steps":["trace[944451357] 'process raft request' (duration: 202.505094ms)","trace[944451357] 'compare' (duration: 147.643786ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:30:04.430054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.079201Z","time spent":"350.828057ms","remote":"127.0.0.1:40934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:04.65084Z","caller":"traceutil/trace.go:171","msg":"trace[404016205] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"557.930609ms","start":"2026-05-09T22:30:04.092876Z","end":"2026-05-09T22:30:04.650807Z","steps":["trace[404016205] 'process raft request' (duration: 557.667092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:04.651142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.092862Z","time spent":"558.148645ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:04.651158Z","caller":"traceutil/trace.go:171","msg":"trace[1755794139] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"460.13707ms","start":"2026-05-09T22:30:04.190954Z","end":"2026-05-09T22:30:04.651091Z","steps":["trace[1755794139] 'process raft request' (duration: 459.802511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:04.651282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.701879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:04.651096Z","caller":"traceutil/trace.go:171","msg":"trace[1592767202] linearizableReadLoop","detail":"{readStateIndex:25601; appliedIndex:25598; }","duration":"417.457953ms","start":"2026-05-09T22:30:04.23356Z","end":"2026-05-09T22:30:04.651018Z","steps":["trace[1592767202] 'read index received' (duration: 48.192737ms)","trace[1592767202] 'applied index is now lower than readState.Index' (duration: 369.262405ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:04.651368Z","caller":"traceutil/trace.go:171","msg":"trace[2117261424] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24809; }","duration":"417.843173ms","start":"2026-05-09T22:30:04.233501Z","end":"2026-05-09T22:30:04.651344Z","steps":["trace[2117261424] 'agreement among raft nodes before linearized reading' (duration: 417.684569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:04.651429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.233478Z","time spent":"417.934725ms","remote":"127.0.0.1:53614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:30:04.651434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.190929Z","time spent":"460.369016ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:30:04.651507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.046011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:04.651593Z","caller":"traceutil/trace.go:171","msg":"trace[582823408] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24809; }","duration":"309.205956ms","start":"2026-05-09T22:30:04.342366Z","end":"2026-05-09T22:30:04.651572Z","steps":["trace[582823408] 'agreement among raft nodes before linearized reading' (duration: 309.057232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:04.651637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.342338Z","time spent":"309.289457ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:30:04.651958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.112743ms","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-05-09T22:30:04.652001Z","caller":"traceutil/trace.go:171","msg":"trace[9367420] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:24809; }","duration":"207.207005ms","start":"2026-05-09T22:30:04.444783Z","end":"2026-05-09T22:30:04.651989Z","steps":["trace[9367420] 'agreement among raft nodes before linearized reading' (duration: 207.065732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:04.651975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.308706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:04.652146Z","caller":"traceutil/trace.go:171","msg":"trace[1194268432] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:24809; }","duration":"306.5161ms","start":"2026-05-09T22:30:04.345615Z","end":"2026-05-09T22:30:04.652131Z","steps":["trace[1194268432] 'agreement among raft nodes before linearized reading' (duration: 306.301795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:04.652213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.345591Z","time spent":"306.608102ms","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:30:04.652212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.40772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-09T22:30:04.652313Z","caller":"traceutil/trace.go:171","msg":"trace[1356329029] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24809; }","duration":"220.557104ms","start":"2026-05-09T22:30:04.431746Z","end":"2026-05-09T22:30:04.652303Z","steps":["trace[1356329029] 'agreement among raft nodes before linearized reading' (duration: 220.41931ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:05.063929Z","caller":"traceutil/trace.go:171","msg":"trace[1644520841] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"319.651554ms","start":"2026-05-09T22:30:04.744252Z","end":"2026-05-09T22:30:05.063904Z","steps":["trace[1644520841] 'process raft request' (duration: 319.48398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:05.064204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.744229Z","time spent":"319.849208ms","remote":"127.0.0.1:41178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":868,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:05.074429Z","caller":"traceutil/trace.go:171","msg":"trace[2043911437] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"328.776442ms","start":"2026-05-09T22:30:04.745626Z","end":"2026-05-09T22:30:05.074403Z","steps":["trace[2043911437] 'process raft request' (duration: 328.461174ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:05.074478Z","caller":"traceutil/trace.go:171","msg":"trace[107252705] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"159.469918ms","start":"2026-05-09T22:30:04.914984Z","end":"2026-05-09T22:30:05.074454Z","steps":["trace[107252705] 'process raft request' (duration: 159.293974ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:05.07441Z","caller":"traceutil/trace.go:171","msg":"trace[307235465] linearizableReadLoop","detail":"{readStateIndex:25608; appliedIndex:25606; }","duration":"303.97246ms","start":"2026-05-09T22:30:04.770371Z","end":"2026-05-09T22:30:05.074344Z","steps":["trace[307235465] 'read index received' (duration: 293.399118ms)","trace[307235465] 'applied index is now lower than readState.Index' (duration: 10.572132ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:30:05.074568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.745611Z","time spent":"328.887574ms","remote":"127.0.0.1:41178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:30:05.074623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.253247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-09T22:30:05.074738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.069131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:05.074753Z","caller":"traceutil/trace.go:171","msg":"trace[1257521895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24817; }","duration":"304.442831ms","start":"2026-05-09T22:30:04.770295Z","end":"2026-05-09T22:30:05.074738Z","steps":["trace[1257521895] 'agreement among raft nodes before linearized reading' (duration: 304.186775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:05.074756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.33947ms","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-05-09T22:30:05.074768Z","caller":"traceutil/trace.go:171","msg":"trace[1028269866] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24817; }","duration":"288.151753ms","start":"2026-05-09T22:30:04.786607Z","end":"2026-05-09T22:30:05.074758Z","steps":["trace[1028269866] 'agreement among raft nodes before linearized reading' (duration: 288.097182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:05.074754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.314398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T22:30:05.074799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.770273Z","time spent":"304.515744ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T22:30:05.074838Z","caller":"traceutil/trace.go:171","msg":"trace[285765714] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:24817; }","duration":"144.44078ms","start":"2026-05-09T22:30:04.930379Z","end":"2026-05-09T22:30:05.07482Z","steps":["trace[285765714] 'agreement among raft nodes before linearized reading' (duration: 144.276047ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:05.074796Z","caller":"traceutil/trace.go:171","msg":"trace[1465195139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24817; }","duration":"304.422471ms","start":"2026-05-09T22:30:04.770361Z","end":"2026-05-09T22:30:05.074784Z","steps":["trace[1465195139] 'agreement among raft nodes before linearized reading' (duration: 304.283197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:05.074906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:04.770332Z","time spent":"304.564904ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T22:30:05.074754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.455497ms","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-05-09T22:30:05.075013Z","caller":"traceutil/trace.go:171","msg":"trace[1387657179] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24817; }","duration":"190.731643ms","start":"2026-05-09T22:30:04.884237Z","end":"2026-05-09T22:30:05.074969Z","steps":["trace[1387657179] 'agreement among raft nodes before linearized reading' (duration: 190.435336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:05.376548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.088458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:30:05.376625Z","caller":"traceutil/trace.go:171","msg":"trace[827519387] linearizableReadLoop","detail":"{readStateIndex:25610; appliedIndex:25609; }","duration":"298.566442ms","start":"2026-05-09T22:30:05.078047Z","end":"2026-05-09T22:30:05.376614Z","steps":["trace[827519387] 'read index received' (duration: 103.363042ms)","trace[827519387] 'applied index is now lower than readState.Index' (duration: 195.20243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:30:05.376688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.647014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:05.376703Z","caller":"traceutil/trace.go:171","msg":"trace[1021483353] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24818; }","duration":"298.705925ms","start":"2026-05-09T22:30:05.077993Z","end":"2026-05-09T22:30:05.376699Z","steps":["trace[1021483353] 'agreement among raft nodes before linearized reading' (duration: 298.668274ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:05.376721Z","caller":"traceutil/trace.go:171","msg":"trace[271537885] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"299.028972ms","start":"2026-05-09T22:30:05.077648Z","end":"2026-05-09T22:30:05.376677Z","steps":["trace[271537885] 'process raft request' (duration: 103.740301ms)","trace[271537885] 'compare' (duration: 194.936622ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:05.380281Z","caller":"traceutil/trace.go:171","msg":"trace[583820938] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"299.550195ms","start":"2026-05-09T22:30:05.080707Z","end":"2026-05-09T22:30:05.380257Z","steps":["trace[583820938] 'process raft request' (duration: 299.467423ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:05.380309Z","caller":"traceutil/trace.go:171","msg":"trace[1482847846] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"300.895227ms","start":"2026-05-09T22:30:05.079384Z","end":"2026-05-09T22:30:05.380279Z","steps":["trace[1482847846] 'process raft request' (duration: 300.399355ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:05.380387Z","caller":"traceutil/trace.go:171","msg":"trace[150956580] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"300.600189ms","start":"2026-05-09T22:30:05.079765Z","end":"2026-05-09T22:30:05.380366Z","steps":["trace[150956580] 'process raft request' (duration: 300.361623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:05.380475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:05.079369Z","time spent":"301.0117ms","remote":"127.0.0.1:41146","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-05-09T22:30:05.38051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:05.079755Z","time spent":"300.683292ms","remote":"127.0.0.1:41146","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-05-09T22:30:05.392104Z","caller":"traceutil/trace.go:171","msg":"trace[6110253] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"179.099875ms","start":"2026-05-09T22:30:05.212961Z","end":"2026-05-09T22:30:05.392061Z","steps":["trace[6110253] 'process raft request' (duration: 178.823009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:05.392196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.606824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:05.392299Z","caller":"traceutil/trace.go:171","msg":"trace[929803673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24822; }","duration":"246.755437ms","start":"2026-05-09T22:30:05.145528Z","end":"2026-05-09T22:30:05.392283Z","steps":["trace[929803673] 'agreement among raft nodes before linearized reading' (duration: 246.611584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:05.392301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.757918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:30:05.392341Z","caller":"traceutil/trace.go:171","msg":"trace[296555412] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24822; }","duration":"118.83259ms","start":"2026-05-09T22:30:05.273499Z","end":"2026-05-09T22:30:05.392331Z","steps":["trace[296555412] 'agreement among raft nodes before linearized reading' (duration: 118.705147ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:05.685544Z","caller":"traceutil/trace.go:171","msg":"trace[55676052] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"207.290877ms","start":"2026-05-09T22:30:05.478228Z","end":"2026-05-09T22:30:05.685519Z","steps":["trace[55676052] 'process raft request' (duration: 207.124023ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:06.881726Z","caller":"traceutil/trace.go:171","msg":"trace[1045579809] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"143.713432ms","start":"2026-05-09T22:30:06.73799Z","end":"2026-05-09T22:30:06.881703Z","steps":["trace[1045579809] 'process raft request' (duration: 133.743974ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:06.885546Z","caller":"traceutil/trace.go:171","msg":"trace[1073997089] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"147.095363ms","start":"2026-05-09T22:30:06.738433Z","end":"2026-05-09T22:30:06.885528Z","steps":["trace[1073997089] 'process raft request' (duration: 146.95649ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:06.886018Z","caller":"traceutil/trace.go:171","msg":"trace[759799941] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"147.477641ms","start":"2026-05-09T22:30:06.738499Z","end":"2026-05-09T22:30:06.885977Z","steps":["trace[759799941] 'process raft request' (duration: 146.99602ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:11.272703Z","caller":"traceutil/trace.go:171","msg":"trace[1988036348] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"526.532131ms","start":"2026-05-09T22:30:10.746147Z","end":"2026-05-09T22:30:11.272679Z","steps":["trace[1988036348] 'process raft request' (duration: 526.346036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.272866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:10.746117Z","time spent":"526.661064ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:11.315106Z","caller":"traceutil/trace.go:171","msg":"trace[1617112355] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"562.708622ms","start":"2026-05-09T22:30:10.752372Z","end":"2026-05-09T22:30:11.315081Z","steps":["trace[1617112355] 'process raft request' (duration: 562.61264ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:11.31509Z","caller":"traceutil/trace.go:171","msg":"trace[1610898317] linearizableReadLoop","detail":"{readStateIndex:25663; appliedIndex:25659; }","duration":"419.511291ms","start":"2026-05-09T22:30:10.895551Z","end":"2026-05-09T22:30:11.315063Z","steps":["trace[1610898317] 'read index received' (duration: 377.025839ms)","trace[1610898317] 'applied index is now lower than readState.Index' (duration: 42.484262ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:11.315163Z","caller":"traceutil/trace.go:171","msg":"trace[1621019771] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"567.079376ms","start":"2026-05-09T22:30:10.748074Z","end":"2026-05-09T22:30:11.315153Z","steps":["trace[1621019771] 'process raft request' (duration: 566.866011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.315216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:10.752357Z","time spent":"562.803954ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:11.315095Z","caller":"traceutil/trace.go:171","msg":"trace[194225403] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"567.069906ms","start":"2026-05-09T22:30:10.747988Z","end":"2026-05-09T22:30:11.315058Z","steps":["trace[194225403] 'process raft request' (duration: 566.79533ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:11.315217Z","caller":"traceutil/trace.go:171","msg":"trace[35061183] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"217.994311ms","start":"2026-05-09T22:30:11.097203Z","end":"2026-05-09T22:30:11.315197Z","steps":["trace[35061183] 'process raft request' (duration: 217.816297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.315654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:10.747975Z","time spent":"567.64147ms","remote":"127.0.0.1:41146","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-05-09T22:30:11.315678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.012186ms","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-05-09T22:30:11.315285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:10.748063Z","time spent":"567.139147ms","remote":"127.0.0.1:41146","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-05-09T22:30:11.31543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.863211ms","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-05-09T22:30:11.315771Z","caller":"traceutil/trace.go:171","msg":"trace[33205469] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:24871; }","duration":"415.130609ms","start":"2026-05-09T22:30:10.900603Z","end":"2026-05-09T22:30:11.315734Z","steps":["trace[33205469] 'agreement among raft nodes before linearized reading' (duration: 414.914264ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:11.315806Z","caller":"traceutil/trace.go:171","msg":"trace[323520545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24871; }","duration":"420.308391ms","start":"2026-05-09T22:30:10.895485Z","end":"2026-05-09T22:30:11.315794Z","steps":["trace[323520545] 'agreement among raft nodes before linearized reading' (duration: 419.717296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.315442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.715275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T22:30:11.315814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:10.900586Z","time spent":"415.21858ms","remote":"127.0.0.1:41146","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-05-09T22:30:11.31584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:10.895461Z","time spent":"420.370592ms","remote":"127.0.0.1:41146","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":"warn","ts":"2026-05-09T22:30:11.315499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.121548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:11.315891Z","caller":"traceutil/trace.go:171","msg":"trace[2140389060] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24871; }","duration":"132.511387ms","start":"2026-05-09T22:30:11.183363Z","end":"2026-05-09T22:30:11.315875Z","steps":["trace[2140389060] 'agreement among raft nodes before linearized reading' (duration: 132.108398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.315504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.470597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:11.3159Z","caller":"traceutil/trace.go:171","msg":"trace[259144602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24871; }","duration":"168.161736ms","start":"2026-05-09T22:30:11.147688Z","end":"2026-05-09T22:30:11.31585Z","steps":["trace[259144602] 'agreement among raft nodes before linearized reading' (duration: 167.711485ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:11.315957Z","caller":"traceutil/trace.go:171","msg":"trace[410597575] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24871; }","duration":"376.924398ms","start":"2026-05-09T22:30:10.939023Z","end":"2026-05-09T22:30:11.315947Z","steps":["trace[410597575] 'agreement among raft nodes before linearized reading' (duration: 376.441486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.315985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:10.938974Z","time spent":"377.00378ms","remote":"127.0.0.1:60386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:30:11.316456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.593844ms","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-05-09T22:30:11.316557Z","caller":"traceutil/trace.go:171","msg":"trace[1465868277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24871; }","duration":"419.737567ms","start":"2026-05-09T22:30:10.896775Z","end":"2026-05-09T22:30:11.316513Z","steps":["trace[1465868277] 'agreement among raft nodes before linearized reading' (duration: 418.592749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.316606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:10.896746Z","time spent":"419.84985ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T22:30:11.624911Z","caller":"traceutil/trace.go:171","msg":"trace[1825654328] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"303.318824ms","start":"2026-05-09T22:30:11.32155Z","end":"2026-05-09T22:30:11.624869Z","steps":["trace[1825654328] 'process raft request' (duration: 303.081139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.625172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:11.321534Z","time spent":"303.516929ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:11.635741Z","caller":"traceutil/trace.go:171","msg":"trace[1140380204] linearizableReadLoop","detail":"{readStateIndex:25667; appliedIndex:25664; }","duration":"280.905451ms","start":"2026-05-09T22:30:11.354807Z","end":"2026-05-09T22:30:11.635712Z","steps":["trace[1140380204] 'read index received' (duration: 269.656333ms)","trace[1140380204] 'applied index is now lower than readState.Index' (duration: 11.247758ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:11.636126Z","caller":"traceutil/trace.go:171","msg":"trace[1867280841] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"314.182883ms","start":"2026-05-09T22:30:11.32193Z","end":"2026-05-09T22:30:11.636113Z","steps":["trace[1867280841] 'process raft request' (duration: 313.504627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.636249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:11.321912Z","time spent":"314.247514ms","remote":"127.0.0.1:41146","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-05-09T22:30:11.636473Z","caller":"traceutil/trace.go:171","msg":"trace[662365871] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"314.505891ms","start":"2026-05-09T22:30:11.321954Z","end":"2026-05-09T22:30:11.63646Z","steps":["trace[662365871] 'process raft request' (duration: 313.64761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.636551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:11.321934Z","time spent":"314.563362ms","remote":"127.0.0.1:41146","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-05-09T22:30:11.636687Z","caller":"traceutil/trace.go:171","msg":"trace[2097704245] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"223.970425ms","start":"2026-05-09T22:30:11.412706Z","end":"2026-05-09T22:30:11.636676Z","steps":["trace[2097704245] 'process raft request' (duration: 222.93969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.636832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.026967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:11.636865Z","caller":"traceutil/trace.go:171","msg":"trace[416151595] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:24875; }","duration":"282.091039ms","start":"2026-05-09T22:30:11.354764Z","end":"2026-05-09T22:30:11.636855Z","steps":["trace[416151595] 'agreement among raft nodes before linearized reading' (duration: 282.030847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.637169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.766635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T22:30:11.637203Z","caller":"traceutil/trace.go:171","msg":"trace[787126319] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:24875; }","duration":"240.843457ms","start":"2026-05-09T22:30:11.396351Z","end":"2026-05-09T22:30:11.637195Z","steps":["trace[787126319] 'agreement among raft nodes before linearized reading' (duration: 240.676143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.637597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.01138ms","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-05-09T22:30:11.637629Z","caller":"traceutil/trace.go:171","msg":"trace[249129267] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24875; }","duration":"230.074161ms","start":"2026-05-09T22:30:11.407544Z","end":"2026-05-09T22:30:11.637618Z","steps":["trace[249129267] 'agreement among raft nodes before linearized reading' (duration: 229.955367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.637826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.28888ms","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-05-09T22:30:11.637854Z","caller":"traceutil/trace.go:171","msg":"trace[1656493806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24875; }","duration":"234.336442ms","start":"2026-05-09T22:30:11.403509Z","end":"2026-05-09T22:30:11.637845Z","steps":["trace[1656493806] 'agreement among raft nodes before linearized reading' (duration: 234.24921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:11.638013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.605138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T22:30:11.638038Z","caller":"traceutil/trace.go:171","msg":"trace[156180204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24875; }","duration":"234.649099ms","start":"2026-05-09T22:30:11.403381Z","end":"2026-05-09T22:30:11.63803Z","steps":["trace[156180204] 'agreement among raft nodes before linearized reading' (duration: 234.578668ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:11.815495Z","caller":"traceutil/trace.go:171","msg":"trace[1284061047] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"172.680413ms","start":"2026-05-09T22:30:11.642791Z","end":"2026-05-09T22:30:11.815471Z","steps":["trace[1284061047] 'process raft request' (duration: 166.778303ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:12.001935Z","caller":"traceutil/trace.go:171","msg":"trace[573637728] linearizableReadLoop","detail":"{readStateIndex:25671; appliedIndex:25668; }","duration":"293.619994ms","start":"2026-05-09T22:30:11.708285Z","end":"2026-05-09T22:30:12.001905Z","steps":["trace[573637728] 'read index received' (duration: 97.339268ms)","trace[573637728] 'applied index is now lower than readState.Index' (duration: 196.279436ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:12.001978Z","caller":"traceutil/trace.go:171","msg":"trace[155460569] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"358.634302ms","start":"2026-05-09T22:30:11.643276Z","end":"2026-05-09T22:30:12.001911Z","steps":["trace[155460569] 'process raft request' (duration: 328.189107ms)","trace[155460569] 'compare' (duration: 30.206169ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:30:12.002116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.822859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T22:30:12.002126Z","caller":"traceutil/trace.go:171","msg":"trace[933137220] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"358.578571ms","start":"2026-05-09T22:30:11.643344Z","end":"2026-05-09T22:30:12.001922Z","steps":["trace[933137220] 'process raft request' (duration: 358.489709ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:12.002172Z","caller":"traceutil/trace.go:171","msg":"trace[1012964078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24878; }","duration":"293.915041ms","start":"2026-05-09T22:30:11.708248Z","end":"2026-05-09T22:30:12.002164Z","steps":["trace[1012964078] 'agreement among raft nodes before linearized reading' (duration: 293.745827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:12.002187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.679829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T22:30:12.002187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:11.643251Z","time spent":"358.800896ms","remote":"127.0.0.1:41146","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-05-09T22:30:12.002228Z","caller":"traceutil/trace.go:171","msg":"trace[1806158242] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24878; }","duration":"108.795621ms","start":"2026-05-09T22:30:11.893419Z","end":"2026-05-09T22:30:12.002215Z","steps":["trace[1806158242] 'agreement among raft nodes before linearized reading' (duration: 108.674869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:12.002262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:11.643308Z","time spent":"358.872498ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:30:12.249822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.216622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:30:12.24998Z","caller":"traceutil/trace.go:171","msg":"trace[2146656069] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"243.793687ms","start":"2026-05-09T22:30:12.006131Z","end":"2026-05-09T22:30:12.249925Z","steps":["trace[2146656069] 'process raft request' (duration: 139.399481ms)","trace[2146656069] 'compare' (duration: 103.978976ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:15.441043Z","caller":"traceutil/trace.go:171","msg":"trace[2047971779] linearizableReadLoop","detail":"{readStateIndex:25694; appliedIndex:25693; }","duration":"112.914229ms","start":"2026-05-09T22:30:15.328112Z","end":"2026-05-09T22:30:15.441026Z","steps":["trace[2047971779] 'read index received' (duration: 89.755987ms)","trace[2047971779] 'applied index is now lower than readState.Index' (duration: 23.157722ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:15.441286Z","caller":"traceutil/trace.go:171","msg":"trace[1141167419] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"153.340322ms","start":"2026-05-09T22:30:15.287889Z","end":"2026-05-09T22:30:15.441229Z","steps":["trace[1141167419] 'process raft request' (duration: 129.889933ms)","trace[1141167419] 'compare' (duration: 23.172872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:30:15.441342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.224106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/senlin-engine-cleaner-29639430-2b6t4\" ","response":"range_response_count:1 size:6921"} {"level":"info","ts":"2026-05-09T22:30:15.441398Z","caller":"traceutil/trace.go:171","msg":"trace[403152732] range","detail":"{range_begin:/registry/pods/openstack/senlin-engine-cleaner-29639430-2b6t4; range_end:; response_count:1; response_revision:24900; }","duration":"113.326688ms","start":"2026-05-09T22:30:15.328062Z","end":"2026-05-09T22:30:15.441389Z","steps":["trace[403152732] 'agreement among raft nodes before linearized reading' (duration: 113.192875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:15.735682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.328196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:30:15.735979Z","caller":"traceutil/trace.go:171","msg":"trace[116570100] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"406.884101ms","start":"2026-05-09T22:30:15.329061Z","end":"2026-05-09T22:30:15.735945Z","steps":["trace[116570100] 'process raft request' (duration: 251.954321ms)","trace[116570100] 'compare' (duration: 154.165942ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:15.736011Z","caller":"traceutil/trace.go:171","msg":"trace[1579756989] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"405.061978ms","start":"2026-05-09T22:30:15.330918Z","end":"2026-05-09T22:30:15.73598Z","steps":["trace[1579756989] 'process raft request' (duration: 404.910403ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:15.736053Z","caller":"traceutil/trace.go:171","msg":"trace[1639244095] linearizableReadLoop","detail":"{readStateIndex:25698; appliedIndex:25694; }","duration":"294.920625ms","start":"2026-05-09T22:30:15.441105Z","end":"2026-05-09T22:30:15.736026Z","steps":["trace[1639244095] 'read index received' (duration: 139.861871ms)","trace[1639244095] 'applied index is now lower than readState.Index' (duration: 155.057394ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:15.736092Z","caller":"traceutil/trace.go:171","msg":"trace[1173314808] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"402.763993ms","start":"2026-05-09T22:30:15.333309Z","end":"2026-05-09T22:30:15.736073Z","steps":["trace[1173314808] 'process raft request' (duration: 402.596228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:15.736146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:15.329034Z","time spent":"407.010014ms","remote":"127.0.0.1:41146","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-05-09T22:30:15.736154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:15.333287Z","time spent":"402.838645ms","remote":"127.0.0.1:41146","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-05-09T22:30:15.73615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:15.330892Z","time spent":"405.179531ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:15.736129Z","caller":"traceutil/trace.go:171","msg":"trace[939939654] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"402.766713ms","start":"2026-05-09T22:30:15.333326Z","end":"2026-05-09T22:30:15.736093Z","steps":["trace[939939654] 'process raft request' (duration: 402.63191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:15.736274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.134052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-05-09T22:30:15.736336Z","caller":"traceutil/trace.go:171","msg":"trace[1649140922] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24904; }","duration":"382.218645ms","start":"2026-05-09T22:30:15.354101Z","end":"2026-05-09T22:30:15.73632Z","steps":["trace[1649140922] 'agreement among raft nodes before linearized reading' (duration: 382.01048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:15.736351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:15.333308Z","time spent":"402.913186ms","remote":"127.0.0.1:41146","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-05-09T22:30:15.736588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:15.354093Z","time spent":"382.271096ms","remote":"127.0.0.1:41032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-09T22:30:15.748775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.072748ms","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-05-09T22:30:15.748878Z","caller":"traceutil/trace.go:171","msg":"trace[1558159034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24905; }","duration":"109.219931ms","start":"2026-05-09T22:30:15.63964Z","end":"2026-05-09T22:30:15.74886Z","steps":["trace[1558159034] 'agreement among raft nodes before linearized reading' (duration: 109.002236ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:15.748906Z","caller":"traceutil/trace.go:171","msg":"trace[1082085936] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"100.015862ms","start":"2026-05-09T22:30:15.648848Z","end":"2026-05-09T22:30:15.748864Z","steps":["trace[1082085936] 'process raft request' (duration: 99.47099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:15.748931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.95169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29639430-2tnj7\" ","response":"range_response_count:1 size:7365"} {"level":"info","ts":"2026-05-09T22:30:15.748977Z","caller":"traceutil/trace.go:171","msg":"trace[293084574] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29639430-2tnj7; range_end:; response_count:1; response_revision:24905; }","duration":"304.006361ms","start":"2026-05-09T22:30:15.444958Z","end":"2026-05-09T22:30:15.748964Z","steps":["trace[293084574] 'agreement among raft nodes before linearized reading' (duration: 303.903598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:15.749015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:15.444947Z","time spent":"304.059402ms","remote":"127.0.0.1:41060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":7388,"request content":"key:\"/registry/pods/openstack/heat-engine-cleaner-29639430-2tnj7\" "} {"level":"warn","ts":"2026-05-09T22:30:15.748818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.811989ms","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-05-09T22:30:15.749287Z","caller":"traceutil/trace.go:171","msg":"trace[489107441] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24905; }","duration":"213.28712ms","start":"2026-05-09T22:30:15.535942Z","end":"2026-05-09T22:30:15.749229Z","steps":["trace[489107441] 'agreement among raft nodes before linearized reading' (duration: 212.792868ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:15.89023Z","caller":"traceutil/trace.go:171","msg":"trace[1214212250] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"137.827413ms","start":"2026-05-09T22:30:15.752363Z","end":"2026-05-09T22:30:15.89019Z","steps":["trace[1214212250] 'process raft request' (duration: 133.350336ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:15.95714Z","caller":"traceutil/trace.go:171","msg":"trace[2077175012] linearizableReadLoop","detail":"{readStateIndex:25701; appliedIndex:25699; }","duration":"204.236773ms","start":"2026-05-09T22:30:15.75288Z","end":"2026-05-09T22:30:15.957117Z","steps":["trace[2077175012] 'read index received' (duration: 132.846753ms)","trace[2077175012] 'applied index is now lower than readState.Index' (duration: 71.38887ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:15.957168Z","caller":"traceutil/trace.go:171","msg":"trace[447351293] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"204.4759ms","start":"2026-05-09T22:30:15.752654Z","end":"2026-05-09T22:30:15.95713Z","steps":["trace[447351293] 'process raft request' (duration: 204.163893ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:15.957228Z","caller":"traceutil/trace.go:171","msg":"trace[514139227] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"203.927027ms","start":"2026-05-09T22:30:15.753252Z","end":"2026-05-09T22:30:15.957179Z","steps":["trace[514139227] 'process raft request' (duration: 203.767433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:15.957606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.694506ms","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-05-09T22:30:15.957696Z","caller":"traceutil/trace.go:171","msg":"trace[815553875] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24908; }","duration":"204.850969ms","start":"2026-05-09T22:30:15.752826Z","end":"2026-05-09T22:30:15.957677Z","steps":["trace[815553875] 'agreement among raft nodes before linearized reading' (duration: 204.549932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:15.957687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.233971ms","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-05-09T22:30:15.95779Z","caller":"traceutil/trace.go:171","msg":"trace[1263238492] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24908; }","duration":"127.326393ms","start":"2026-05-09T22:30:15.830415Z","end":"2026-05-09T22:30:15.957741Z","steps":["trace[1263238492] 'agreement among raft nodes before linearized reading' (duration: 127.040916ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:26.418381Z","caller":"traceutil/trace.go:171","msg":"trace[988709380] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"108.355241ms","start":"2026-05-09T22:30:26.309999Z","end":"2026-05-09T22:30:26.418355Z","steps":["trace[988709380] 'process raft request' (duration: 108.218578ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:27.502475Z","caller":"traceutil/trace.go:171","msg":"trace[2141881633] linearizableReadLoop","detail":"{readStateIndex:25800; appliedIndex:25799; }","duration":"238.572132ms","start":"2026-05-09T22:30:27.263878Z","end":"2026-05-09T22:30:27.50245Z","steps":["trace[2141881633] 'read index received' (duration: 238.274465ms)","trace[2141881633] 'applied index is now lower than readState.Index' (duration: 296.357µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:30:27.502667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.765557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:27.502711Z","caller":"traceutil/trace.go:171","msg":"trace[1590289797] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:25004; }","duration":"238.857939ms","start":"2026-05-09T22:30:27.263841Z","end":"2026-05-09T22:30:27.502699Z","steps":["trace[1590289797] 'agreement among raft nodes before linearized reading' (duration: 238.726346ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:27.503025Z","caller":"traceutil/trace.go:171","msg":"trace[1685204213] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"252.831061ms","start":"2026-05-09T22:30:27.250182Z","end":"2026-05-09T22:30:27.503013Z","steps":["trace[1685204213] 'process raft request' (duration: 251.963591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:27.782955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.399876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T22:30:27.783065Z","caller":"traceutil/trace.go:171","msg":"trace[803414524] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25005; }","duration":"130.554429ms","start":"2026-05-09T22:30:27.65249Z","end":"2026-05-09T22:30:27.783044Z","steps":["trace[803414524] 'count revisions from in-memory index tree' (duration: 130.223112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:28.293794Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102958690128656,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T22:30:28.316909Z","caller":"traceutil/trace.go:171","msg":"trace[1725230084] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"530.494355ms","start":"2026-05-09T22:30:27.786396Z","end":"2026-05-09T22:30:28.31689Z","steps":["trace[1725230084] 'process raft request' (duration: 530.391483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:28.317007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:27.786373Z","time spent":"530.584349ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:28.931544Z","caller":"traceutil/trace.go:171","msg":"trace[942194250] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"1.143848264s","start":"2026-05-09T22:30:27.787675Z","end":"2026-05-09T22:30:28.931523Z","steps":["trace[942194250] 'process raft request' (duration: 1.143699181s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:28.931666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:27.787649Z","time spent":"1.143958487s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:28.931782Z","caller":"traceutil/trace.go:171","msg":"trace[74735001] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"1.14279247s","start":"2026-05-09T22:30:27.788969Z","end":"2026-05-09T22:30:28.931762Z","steps":["trace[74735001] 'process raft request' (duration: 1.142522993s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:28.931914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:27.78896Z","time spent":"1.142888532s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:28.931957Z","caller":"traceutil/trace.go:171","msg":"trace[2137241549] linearizableReadLoop","detail":"{readStateIndex:25807; appliedIndex:25802; }","duration":"1.141500518s","start":"2026-05-09T22:30:27.790436Z","end":"2026-05-09T22:30:28.931937Z","steps":["trace[2137241549] 'read index received' (duration: 526.709985ms)","trace[2137241549] 'applied index is now lower than readState.Index' (duration: 614.789903ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:28.932021Z","caller":"traceutil/trace.go:171","msg":"trace[133906590] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"1.142210326s","start":"2026-05-09T22:30:27.789803Z","end":"2026-05-09T22:30:28.932014Z","steps":["trace[133906590] 'process raft request' (duration: 1.142068532s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:28.932063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:27.789774Z","time spent":"1.142266647s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:30:28.932077Z","caller":"traceutil/trace.go:171","msg":"trace[1989752430] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"1.062769733s","start":"2026-05-09T22:30:27.869301Z","end":"2026-05-09T22:30:28.932071Z","steps":["trace[1989752430] 'process raft request' (duration: 1.062609129s)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:28.932121Z","caller":"traceutil/trace.go:171","msg":"trace[684373732] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"1.142416861s","start":"2026-05-09T22:30:27.789695Z","end":"2026-05-09T22:30:28.932112Z","steps":["trace[684373732] 'process raft request' (duration: 1.141997071s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:28.932156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:27.789685Z","time spent":"1.142453671s","remote":"127.0.0.1:41146","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-05-09T22:30:28.932156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141730854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:28.93218Z","caller":"traceutil/trace.go:171","msg":"trace[249362282] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25011; }","duration":"1.141785604s","start":"2026-05-09T22:30:27.790389Z","end":"2026-05-09T22:30:28.932174Z","steps":["trace[249362282] 'agreement among raft nodes before linearized reading' (duration: 1.141744783s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:28.932118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:27.869276Z","time spent":"1.062816624s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:30:28.932212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:27.790378Z","time spent":"1.141829796s","remote":"127.0.0.1:40954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"warn","ts":"2026-05-09T22:30:29.356632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.090028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:30:29.356823Z","caller":"traceutil/trace.go:171","msg":"trace[2023737703] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"863.069747ms","start":"2026-05-09T22:30:28.493693Z","end":"2026-05-09T22:30:29.356763Z","steps":["trace[2023737703] 'process raft request' (duration: 604.705402ms)","trace[2023737703] 'compare' (duration: 257.840311ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:29.356913Z","caller":"traceutil/trace.go:171","msg":"trace[1495127082] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"423.18275ms","start":"2026-05-09T22:30:28.933705Z","end":"2026-05-09T22:30:29.356888Z","steps":["trace[1495127082] 'process raft request' (duration: 423.059117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.356976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.493673Z","time spent":"863.22452ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:30:29.357029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.933684Z","time spent":"423.307012ms","remote":"127.0.0.1:46330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1561,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T22:30:29.357246Z","caller":"traceutil/trace.go:171","msg":"trace[1360299988] linearizableReadLoop","detail":"{readStateIndex:25808; appliedIndex:25807; }","duration":"425.243258ms","start":"2026-05-09T22:30:28.931976Z","end":"2026-05-09T22:30:29.357219Z","steps":["trace[1360299988] 'read index received' (duration: 166.412033ms)","trace[1360299988] 'applied index is now lower than readState.Index' (duration: 258.828595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:30:29.357411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263659038s","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-05-09T22:30:29.35745Z","caller":"traceutil/trace.go:171","msg":"trace[761115681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25013; }","duration":"1.26373223s","start":"2026-05-09T22:30:28.093707Z","end":"2026-05-09T22:30:29.35744Z","steps":["trace[761115681] 'agreement among raft nodes before linearized reading' (duration: 1.263587446s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.357479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.093696Z","time spent":"1.26377692s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T22:30:29.357823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41069873s","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-05-09T22:30:29.357868Z","caller":"traceutil/trace.go:171","msg":"trace[1689652622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25013; }","duration":"1.410799872s","start":"2026-05-09T22:30:27.947056Z","end":"2026-05-09T22:30:29.357856Z","steps":["trace[1689652622] 'agreement among raft nodes before linearized reading' (duration: 1.41027718s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.357902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:27.947035Z","time spent":"1.410859394s","remote":"127.0.0.1:41146","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":"warn","ts":"2026-05-09T22:30:29.357884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211189038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T22:30:29.357961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342406643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-09T22:30:29.358043Z","caller":"traceutil/trace.go:171","msg":"trace[437912511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25013; }","duration":"1.342530886s","start":"2026-05-09T22:30:28.015489Z","end":"2026-05-09T22:30:29.35802Z","steps":["trace[437912511] 'agreement among raft nodes before linearized reading' (duration: 1.342289971s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.358038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343737415s","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":"warn","ts":"2026-05-09T22:30:29.358099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.015412Z","time spent":"1.34267535s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T22:30:29.358098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259053398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-09T22:30:29.358127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270196883s","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-05-09T22:30:29.357854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277013096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:29.358261Z","caller":"traceutil/trace.go:171","msg":"trace[1960382982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25013; }","duration":"1.259196321s","start":"2026-05-09T22:30:28.098986Z","end":"2026-05-09T22:30:29.358182Z","steps":["trace[1960382982] 'agreement among raft nodes before linearized reading' (duration: 1.258852753s)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:29.358293Z","caller":"traceutil/trace.go:171","msg":"trace[444880652] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25013; }","duration":"1.277540018s","start":"2026-05-09T22:30:28.080741Z","end":"2026-05-09T22:30:29.358281Z","steps":["trace[444880652] 'agreement among raft nodes before linearized reading' (duration: 1.277014656s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.358139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.906108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T22:30:29.358286Z","caller":"traceutil/trace.go:171","msg":"trace[374401847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25013; }","duration":"1.21130483s","start":"2026-05-09T22:30:28.14664Z","end":"2026-05-09T22:30:29.357945Z","steps":["trace[374401847] 'agreement among raft nodes before linearized reading' (duration: 1.211148867s)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:29.358248Z","caller":"traceutil/trace.go:171","msg":"trace[624178260] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25013; }","duration":"1.270353927s","start":"2026-05-09T22:30:28.087876Z","end":"2026-05-09T22:30:29.35823Z","steps":["trace[624178260] 'agreement among raft nodes before linearized reading' (duration: 1.27006521s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.35833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.098976Z","time spent":"1.259339114s","remote":"127.0.0.1:41146","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-05-09T22:30:29.358339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.08072Z","time spent":"1.2776042s","remote":"127.0.0.1:60210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"info","ts":"2026-05-09T22:30:29.35835Z","caller":"traceutil/trace.go:171","msg":"trace[697533780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25013; }","duration":"935.153434ms","start":"2026-05-09T22:30:28.423184Z","end":"2026-05-09T22:30:29.358338Z","steps":["trace[697533780] 'agreement among raft nodes before linearized reading' (duration: 934.884277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.358387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.423164Z","time spent":"935.213516ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T22:30:29.358131Z","caller":"traceutil/trace.go:171","msg":"trace[686927931] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25013; }","duration":"1.343862748s","start":"2026-05-09T22:30:28.014252Z","end":"2026-05-09T22:30:29.358115Z","steps":["trace[686927931] 'agreement among raft nodes before linearized reading' (duration: 1.343684224s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.358404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.087858Z","time spent":"1.27050927s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-09T22:30:29.358514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.014236Z","time spent":"1.344199926s","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-09T22:30:29.358615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.984197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-05-09T22:30:29.358395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.146627Z","time spent":"1.211721311s","remote":"127.0.0.1:40912","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-05-09T22:30:29.358679Z","caller":"traceutil/trace.go:171","msg":"trace[371683795] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25013; }","duration":"523.757195ms","start":"2026-05-09T22:30:28.834901Z","end":"2026-05-09T22:30:29.358659Z","steps":["trace[371683795] 'agreement among raft nodes before linearized reading' (duration: 522.897785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.358722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:30:28.834882Z","time spent":"523.829456ms","remote":"127.0.0.1:40976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-05-09T22:30:29.585524Z","caller":"traceutil/trace.go:171","msg":"trace[813667115] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"220.575283ms","start":"2026-05-09T22:30:29.364922Z","end":"2026-05-09T22:30:29.585497Z","steps":["trace[813667115] 'process raft request' (duration: 220.398859ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:29.589642Z","caller":"traceutil/trace.go:171","msg":"trace[1107864197] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"224.442866ms","start":"2026-05-09T22:30:29.365177Z","end":"2026-05-09T22:30:29.58962Z","steps":["trace[1107864197] 'process raft request' (duration: 224.388425ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:29.589649Z","caller":"traceutil/trace.go:171","msg":"trace[688419726] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"224.671791ms","start":"2026-05-09T22:30:29.364952Z","end":"2026-05-09T22:30:29.589624Z","steps":["trace[688419726] 'process raft request' (duration: 224.522747ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:29.590001Z","caller":"traceutil/trace.go:171","msg":"trace[1103077027] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"224.688301ms","start":"2026-05-09T22:30:29.365289Z","end":"2026-05-09T22:30:29.589977Z","steps":["trace[1103077027] 'process raft request' (duration: 224.305692ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:29.590335Z","caller":"traceutil/trace.go:171","msg":"trace[408637200] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"224.960497ms","start":"2026-05-09T22:30:29.365361Z","end":"2026-05-09T22:30:29.590322Z","steps":["trace[408637200] 'process raft request' (duration: 224.509947ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:29.590452Z","caller":"traceutil/trace.go:171","msg":"trace[242376208] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"224.785644ms","start":"2026-05-09T22:30:29.365635Z","end":"2026-05-09T22:30:29.590421Z","steps":["trace[242376208] 'process raft request' (duration: 224.478006ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:29.590523Z","caller":"traceutil/trace.go:171","msg":"trace[343006842] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"224.764293ms","start":"2026-05-09T22:30:29.365743Z","end":"2026-05-09T22:30:29.590507Z","steps":["trace[343006842] 'process raft request' (duration: 224.533997ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:29.593604Z","caller":"traceutil/trace.go:171","msg":"trace[1888115661] linearizableReadLoop","detail":"{readStateIndex:25816; appliedIndex:25810; }","duration":"226.369642ms","start":"2026-05-09T22:30:29.36717Z","end":"2026-05-09T22:30:29.593539Z","steps":["trace[1888115661] 'read index received' (duration: 218.276999ms)","trace[1888115661] 'applied index is now lower than readState.Index' (duration: 8.090703ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:30:29.5947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.596747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1579"} {"level":"info","ts":"2026-05-09T22:30:29.594829Z","caller":"traceutil/trace.go:171","msg":"trace[1633054637] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25021; }","duration":"227.630343ms","start":"2026-05-09T22:30:29.367142Z","end":"2026-05-09T22:30:29.594772Z","steps":["trace[1633054637] 'agreement among raft nodes before linearized reading' (duration: 226.523556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:29.595018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.15479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:29.595105Z","caller":"traceutil/trace.go:171","msg":"trace[1273525562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25021; }","duration":"227.237562ms","start":"2026-05-09T22:30:29.367831Z","end":"2026-05-09T22:30:29.595069Z","steps":["trace[1273525562] 'agreement among raft nodes before linearized reading' (duration: 227.073738ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:30:36.632958Z","caller":"traceutil/trace.go:171","msg":"trace[77533130] linearizableReadLoop","detail":"{readStateIndex:25944; appliedIndex:25943; }","duration":"150.773201ms","start":"2026-05-09T22:30:36.482153Z","end":"2026-05-09T22:30:36.632926Z","steps":["trace[77533130] 'read index received' (duration: 137.092715ms)","trace[77533130] 'applied index is now lower than readState.Index' (duration: 13.679206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:30:36.633232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.046587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:36.633275Z","caller":"traceutil/trace.go:171","msg":"trace[27792968] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"208.629239ms","start":"2026-05-09T22:30:36.424624Z","end":"2026-05-09T22:30:36.633253Z","steps":["trace[27792968] 'process raft request' (duration: 194.706957ms)","trace[27792968] 'compare' (duration: 13.152574ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:30:36.633365Z","caller":"traceutil/trace.go:171","msg":"trace[1211103942] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25145; }","duration":"151.201241ms","start":"2026-05-09T22:30:36.482114Z","end":"2026-05-09T22:30:36.633315Z","steps":["trace[1211103942] 'agreement among raft nodes before linearized reading' (duration: 150.988586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:30:36.633312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.004598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:30:36.633579Z","caller":"traceutil/trace.go:171","msg":"trace[612181881] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25145; }","duration":"141.256624ms","start":"2026-05-09T22:30:36.492256Z","end":"2026-05-09T22:30:36.633512Z","steps":["trace[612181881] 'agreement among raft nodes before linearized reading' (duration: 140.977417ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:07.95849Z","caller":"traceutil/trace.go:171","msg":"trace[956893352] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"179.928935ms","start":"2026-05-09T22:31:07.778541Z","end":"2026-05-09T22:31:07.95847Z","steps":["trace[956893352] 'process raft request' (duration: 129.583377ms)","trace[956893352] 'compare' (duration: 50.206585ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:07.961319Z","caller":"traceutil/trace.go:171","msg":"trace[2106177978] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"180.313365ms","start":"2026-05-09T22:31:07.780979Z","end":"2026-05-09T22:31:07.961292Z","steps":["trace[2106177978] 'process raft request' (duration: 180.231943ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:07.961419Z","caller":"traceutil/trace.go:171","msg":"trace[831134000] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"134.254037ms","start":"2026-05-09T22:31:07.827153Z","end":"2026-05-09T22:31:07.961407Z","steps":["trace[831134000] 'process raft request' (duration: 134.096764ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:07.961416Z","caller":"traceutil/trace.go:171","msg":"trace[2023199596] linearizableReadLoop","detail":"{readStateIndex:26204; appliedIndex:26201; }","duration":"177.810865ms","start":"2026-05-09T22:31:07.783574Z","end":"2026-05-09T22:31:07.961385Z","steps":["trace[2023199596] 'read index received' (duration: 124.69921ms)","trace[2023199596] 'applied index is now lower than readState.Index' (duration: 53.109675ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:07.961355Z","caller":"traceutil/trace.go:171","msg":"trace[1143162692] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"180.97623ms","start":"2026-05-09T22:31:07.780336Z","end":"2026-05-09T22:31:07.961313Z","steps":["trace[1143162692] 'process raft request' (duration: 180.721674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:07.961618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.02717ms","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-05-09T22:31:07.961672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.424996ms","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-05-09T22:31:07.961703Z","caller":"traceutil/trace.go:171","msg":"trace[245968255] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25399; }","duration":"177.481437ms","start":"2026-05-09T22:31:07.784212Z","end":"2026-05-09T22:31:07.961694Z","steps":["trace[245968255] 'agreement among raft nodes before linearized reading' (duration: 177.362974ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:07.961707Z","caller":"traceutil/trace.go:171","msg":"trace[440927808] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25399; }","duration":"178.140743ms","start":"2026-05-09T22:31:07.783544Z","end":"2026-05-09T22:31:07.961685Z","steps":["trace[440927808] 'agreement among raft nodes before linearized reading' (duration: 177.946908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:07.961709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.668792ms","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-05-09T22:31:07.961755Z","caller":"traceutil/trace.go:171","msg":"trace[951762561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25399; }","duration":"177.742973ms","start":"2026-05-09T22:31:07.783999Z","end":"2026-05-09T22:31:07.961741Z","steps":["trace[951762561] 'agreement among raft nodes before linearized reading' (duration: 177.557959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:09.183137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.418985ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T22:31:09.183248Z","caller":"traceutil/trace.go:171","msg":"trace[337758338] linearizableReadLoop","detail":"{readStateIndex:26212; appliedIndex:26210; }","duration":"137.424203ms","start":"2026-05-09T22:31:09.0458Z","end":"2026-05-09T22:31:09.183224Z","steps":["trace[337758338] 'read index received' (duration: 1.064676ms)","trace[337758338] 'applied index is now lower than readState.Index' (duration: 136.357747ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:09.183373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.557727ms","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-05-09T22:31:09.183413Z","caller":"traceutil/trace.go:171","msg":"trace[1534685918] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25405; }","duration":"137.640188ms","start":"2026-05-09T22:31:09.045761Z","end":"2026-05-09T22:31:09.183401Z","steps":["trace[1534685918] 'agreement among raft nodes before linearized reading' (duration: 137.556276ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:09.345422Z","caller":"traceutil/trace.go:171","msg":"trace[173610426] linearizableReadLoop","detail":"{readStateIndex:26216; appliedIndex:26212; }","duration":"162.119322ms","start":"2026-05-09T22:31:09.183282Z","end":"2026-05-09T22:31:09.345401Z","steps":["trace[173610426] 'read index received' (duration: 10.574712ms)","trace[173610426] 'applied index is now lower than readState.Index' (duration: 151.54323ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:09.345455Z","caller":"traceutil/trace.go:171","msg":"trace[352777281] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"247.30009ms","start":"2026-05-09T22:31:09.098138Z","end":"2026-05-09T22:31:09.345438Z","steps":["trace[352777281] 'process raft request' (duration: 247.169876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:09.345572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.852445ms","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-05-09T22:31:09.345539Z","caller":"traceutil/trace.go:171","msg":"trace[159964048] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"249.132874ms","start":"2026-05-09T22:31:09.096362Z","end":"2026-05-09T22:31:09.345495Z","steps":["trace[159964048] 'process raft request' (duration: 248.797286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:09.345641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.30779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:09.345605Z","caller":"traceutil/trace.go:171","msg":"trace[267474035] 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:25409; }","duration":"258.927497ms","start":"2026-05-09T22:31:09.086669Z","end":"2026-05-09T22:31:09.345596Z","steps":["trace[267474035] 'agreement among raft nodes before linearized reading' (duration: 258.815655ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:09.345663Z","caller":"traceutil/trace.go:171","msg":"trace[956326556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25409; }","duration":"200.409393ms","start":"2026-05-09T22:31:09.145249Z","end":"2026-05-09T22:31:09.345658Z","steps":["trace[956326556] 'agreement among raft nodes before linearized reading' (duration: 200.371092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:09.345662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.895156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:31:09.345759Z","caller":"traceutil/trace.go:171","msg":"trace[96794346] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"247.148027ms","start":"2026-05-09T22:31:09.098604Z","end":"2026-05-09T22:31:09.345752Z","steps":["trace[96794346] 'process raft request' (duration: 246.760158ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:09.345785Z","caller":"traceutil/trace.go:171","msg":"trace[609031499] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25409; }","duration":"178.099382ms","start":"2026-05-09T22:31:09.167652Z","end":"2026-05-09T22:31:09.345752Z","steps":["trace[609031499] 'agreement among raft nodes before linearized reading' (duration: 177.871237ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:09.928644Z","caller":"traceutil/trace.go:171","msg":"trace[1115192487] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"133.147952ms","start":"2026-05-09T22:31:09.795446Z","end":"2026-05-09T22:31:09.928594Z","steps":["trace[1115192487] 'process raft request' (duration: 131.81563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:10.22529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.696163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:31:10.225396Z","caller":"traceutil/trace.go:171","msg":"trace[74064035] linearizableReadLoop","detail":"{readStateIndex:26219; appliedIndex:26218; }","duration":"256.174001ms","start":"2026-05-09T22:31:09.969203Z","end":"2026-05-09T22:31:10.225377Z","steps":["trace[74064035] 'read index received' (duration: 117.320944ms)","trace[74064035] 'applied index is now lower than readState.Index' (duration: 138.851647ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:10.225854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.643144ms","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-05-09T22:31:10.225914Z","caller":"traceutil/trace.go:171","msg":"trace[235451563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25412; }","duration":"256.745176ms","start":"2026-05-09T22:31:09.969153Z","end":"2026-05-09T22:31:10.225898Z","steps":["trace[235451563] 'agreement among raft nodes before linearized reading' (duration: 256.578882ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:10.226045Z","caller":"traceutil/trace.go:171","msg":"trace[960739380] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"258.375955ms","start":"2026-05-09T22:31:09.967657Z","end":"2026-05-09T22:31:10.226033Z","steps":["trace[960739380] 'process raft request' (duration: 118.853322ms)","trace[960739380] 'compare' (duration: 138.359385ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:10.379539Z","caller":"traceutil/trace.go:171","msg":"trace[1234809973] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"410.20481ms","start":"2026-05-09T22:31:09.969314Z","end":"2026-05-09T22:31:10.379519Z","steps":["trace[1234809973] 'process raft request' (duration: 410.171549ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:10.379569Z","caller":"traceutil/trace.go:171","msg":"trace[345095736] linearizableReadLoop","detail":"{readStateIndex:26221; appliedIndex:26221; }","duration":"154.106771ms","start":"2026-05-09T22:31:10.225428Z","end":"2026-05-09T22:31:10.379535Z","steps":["trace[345095736] 'read index received' (duration: 154.09213ms)","trace[345095736] 'applied index is now lower than readState.Index' (duration: 12.271µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:10.379665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:09.969295Z","time spent":"410.305883ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:31:10.379737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.10848ms","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":"warn","ts":"2026-05-09T22:31:10.379766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.93103ms","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-05-09T22:31:10.379801Z","caller":"traceutil/trace.go:171","msg":"trace[1933210849] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25414; }","duration":"409.019393ms","start":"2026-05-09T22:31:09.970773Z","end":"2026-05-09T22:31:10.379792Z","steps":["trace[1933210849] 'agreement among raft nodes before linearized reading' (duration: 408.857539ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:10.379804Z","caller":"traceutil/trace.go:171","msg":"trace[753285234] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25414; }","duration":"408.220283ms","start":"2026-05-09T22:31:09.971566Z","end":"2026-05-09T22:31:10.379786Z","steps":["trace[753285234] 'agreement among raft nodes before linearized reading' (duration: 407.993217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:10.379831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:09.970745Z","time spent":"409.079493ms","remote":"127.0.0.1:41146","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-05-09T22:31:10.379835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:09.971551Z","time spent":"408.278684ms","remote":"127.0.0.1:41146","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":"info","ts":"2026-05-09T22:31:10.379764Z","caller":"traceutil/trace.go:171","msg":"trace[1955261955] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"410.480498ms","start":"2026-05-09T22:31:09.969276Z","end":"2026-05-09T22:31:10.379756Z","steps":["trace[1955261955] 'process raft request' (duration: 410.105868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:10.379894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:09.969257Z","time spent":"410.61243ms","remote":"127.0.0.1:41146","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-05-09T22:31:10.419813Z","caller":"traceutil/trace.go:171","msg":"trace[1422356751] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"190.376354ms","start":"2026-05-09T22:31:10.229416Z","end":"2026-05-09T22:31:10.419792Z","steps":["trace[1422356751] 'process raft request' (duration: 190.255441ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:10.419999Z","caller":"traceutil/trace.go:171","msg":"trace[1832347150] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"182.798914ms","start":"2026-05-09T22:31:10.237163Z","end":"2026-05-09T22:31:10.419962Z","steps":["trace[1832347150] 'process raft request' (duration: 182.593229ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:10.933988Z","caller":"traceutil/trace.go:171","msg":"trace[843204433] linearizableReadLoop","detail":"{readStateIndex:26226; appliedIndex:26225; }","duration":"125.094789ms","start":"2026-05-09T22:31:10.808871Z","end":"2026-05-09T22:31:10.933965Z","steps":["trace[843204433] 'read index received' (duration: 124.916705ms)","trace[843204433] 'applied index is now lower than readState.Index' (duration: 177.064µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:10.934192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.294874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:10.934097Z","caller":"traceutil/trace.go:171","msg":"trace[555426763] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"323.812043ms","start":"2026-05-09T22:31:10.610251Z","end":"2026-05-09T22:31:10.934063Z","steps":["trace[555426763] 'process raft request' (duration: 323.581787ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:10.934281Z","caller":"traceutil/trace.go:171","msg":"trace[907778802] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25419; }","duration":"125.429117ms","start":"2026-05-09T22:31:10.808831Z","end":"2026-05-09T22:31:10.93426Z","steps":["trace[907778802] 'agreement among raft nodes before linearized reading' (duration: 125.262463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:10.934454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:10.610226Z","time spent":"324.150951ms","remote":"127.0.0.1:41146","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-05-09T22:31:11.323188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.561517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:11.323275Z","caller":"traceutil/trace.go:171","msg":"trace[1537752242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25419; }","duration":"179.70008ms","start":"2026-05-09T22:31:11.143556Z","end":"2026-05-09T22:31:11.323256Z","steps":["trace[1537752242] 'range keys from in-memory index tree' (duration: 179.431694ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:11.323764Z","caller":"traceutil/trace.go:171","msg":"trace[44189263] transaction","detail":"{read_only:false; response_revision:25420; number_of_response:1; }","duration":"125.737135ms","start":"2026-05-09T22:31:11.197999Z","end":"2026-05-09T22:31:11.323736Z","steps":["trace[44189263] 'process raft request' (duration: 73.4777ms)","trace[44189263] 'compare' (duration: 52.149131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:11.739103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.164311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:31:11.739318Z","caller":"traceutil/trace.go:171","msg":"trace[1011165279] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26227; }","duration":"359.257236ms","start":"2026-05-09T22:31:11.380045Z","end":"2026-05-09T22:31:11.739302Z","steps":["trace[1011165279] 'read index received' (duration: 63.84748ms)","trace[1011165279] 'applied index is now lower than readState.Index' (duration: 295.408766ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:11.739411Z","caller":"traceutil/trace.go:171","msg":"trace[1038640432] transaction","detail":"{read_only:false; response_revision:25421; number_of_response:1; }","duration":"388.791261ms","start":"2026-05-09T22:31:11.350611Z","end":"2026-05-09T22:31:11.739402Z","steps":["trace[1038640432] 'process raft request' (duration: 93.266851ms)","trace[1038640432] 'compare' (duration: 294.717501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:11.739458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:11.350591Z","time spent":"388.840571ms","remote":"127.0.0.1:41146","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-05-09T22:31:11.739446Z","caller":"traceutil/trace.go:171","msg":"trace[1328355658] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"379.332144ms","start":"2026-05-09T22:31:11.360086Z","end":"2026-05-09T22:31:11.739418Z","steps":["trace[1328355658] 'process raft request' (duration: 379.179391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:11.739584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:11.360065Z","time spent":"379.444338ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:31:11.739659Z","caller":"traceutil/trace.go:171","msg":"trace[214697640] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"388.32865ms","start":"2026-05-09T22:31:11.351322Z","end":"2026-05-09T22:31:11.739651Z","steps":["trace[214697640] 'process raft request' (duration: 387.863739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:11.739702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:11.351288Z","time spent":"388.390261ms","remote":"127.0.0.1:41146","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-05-09T22:31:11.739712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.646976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-09T22:31:11.739807Z","caller":"traceutil/trace.go:171","msg":"trace[735732475] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25423; }","duration":"359.789079ms","start":"2026-05-09T22:31:11.379992Z","end":"2026-05-09T22:31:11.739781Z","steps":["trace[735732475] 'agreement among raft nodes before linearized reading' (duration: 359.550863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:11.739905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:11.379976Z","time spent":"359.891012ms","remote":"127.0.0.1:41032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1873,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"info","ts":"2026-05-09T22:31:12.087957Z","caller":"traceutil/trace.go:171","msg":"trace[424585443] linearizableReadLoop","detail":"{readStateIndex:26231; appliedIndex:26230; }","duration":"155.170265ms","start":"2026-05-09T22:31:11.932762Z","end":"2026-05-09T22:31:12.087932Z","steps":["trace[424585443] 'read index received' (duration: 154.95601ms)","trace[424585443] 'applied index is now lower than readState.Index' (duration: 213.315µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:12.088168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.38806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T22:31:12.088187Z","caller":"traceutil/trace.go:171","msg":"trace[2051681960] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"292.291871ms","start":"2026-05-09T22:31:11.795835Z","end":"2026-05-09T22:31:12.088127Z","steps":["trace[2051681960] 'process raft request' (duration: 291.934543ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:12.088217Z","caller":"traceutil/trace.go:171","msg":"trace[1521983819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25424; }","duration":"155.466142ms","start":"2026-05-09T22:31:11.932739Z","end":"2026-05-09T22:31:12.088205Z","steps":["trace[1521983819] 'agreement among raft nodes before linearized reading' (duration: 155.290328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.358823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.017297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:31:12.35892Z","caller":"traceutil/trace.go:171","msg":"trace[943954123] linearizableReadLoop","detail":"{readStateIndex:26232; appliedIndex:26231; }","duration":"128.236144ms","start":"2026-05-09T22:31:12.230669Z","end":"2026-05-09T22:31:12.358905Z","steps":["trace[943954123] 'read index received' (duration: 49.561µs)","trace[943954123] 'applied index is now lower than readState.Index' (duration: 128.185523ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:12.359303Z","caller":"traceutil/trace.go:171","msg":"trace[597885550] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"267.404999ms","start":"2026-05-09T22:31:12.091884Z","end":"2026-05-09T22:31:12.359289Z","steps":["trace[597885550] 'process raft request' (duration: 128.854209ms)","trace[597885550] 'compare' (duration: 137.507246ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:12.359363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.679445ms","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-05-09T22:31:12.359433Z","caller":"traceutil/trace.go:171","msg":"trace[829675676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25425; }","duration":"128.835839ms","start":"2026-05-09T22:31:12.230578Z","end":"2026-05-09T22:31:12.359414Z","steps":["trace[829675676] 'agreement among raft nodes before linearized reading' (duration: 128.624664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.645496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.351694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:31:12.646096Z","caller":"traceutil/trace.go:171","msg":"trace[313458561] linearizableReadLoop","detail":"{readStateIndex:26233; appliedIndex:26232; }","duration":"282.655002ms","start":"2026-05-09T22:31:12.363414Z","end":"2026-05-09T22:31:12.646069Z","steps":["trace[313458561] 'read index received' (duration: 117.597281ms)","trace[313458561] 'applied index is now lower than readState.Index' (duration: 165.056541ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:12.646161Z","caller":"traceutil/trace.go:171","msg":"trace[1524992172] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"283.418261ms","start":"2026-05-09T22:31:12.362691Z","end":"2026-05-09T22:31:12.64611Z","steps":["trace[1524992172] 'process raft request' (duration: 118.38982ms)","trace[1524992172] 'compare' (duration: 163.76171ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:12.64675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.315348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18459"} {"level":"info","ts":"2026-05-09T22:31:12.647616Z","caller":"traceutil/trace.go:171","msg":"trace[1194180861] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25426; }","duration":"284.199009ms","start":"2026-05-09T22:31:12.363398Z","end":"2026-05-09T22:31:12.647597Z","steps":["trace[1194180861] 'agreement among raft nodes before linearized reading' (duration: 282.758495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.647127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.957546ms","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-05-09T22:31:12.647868Z","caller":"traceutil/trace.go:171","msg":"trace[1991793028] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25426; }","duration":"180.741264ms","start":"2026-05-09T22:31:12.467115Z","end":"2026-05-09T22:31:12.647856Z","steps":["trace[1991793028] 'agreement among raft nodes before linearized reading' (duration: 179.948755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.647312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.181854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:12.647974Z","caller":"traceutil/trace.go:171","msg":"trace[289548277] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25426; }","duration":"246.84732ms","start":"2026-05-09T22:31:12.401117Z","end":"2026-05-09T22:31:12.647964Z","steps":["trace[289548277] 'agreement among raft nodes before linearized reading' (duration: 246.159903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.647357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.093805ms","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-05-09T22:31:12.648092Z","caller":"traceutil/trace.go:171","msg":"trace[951716896] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25426; }","duration":"179.838843ms","start":"2026-05-09T22:31:12.468239Z","end":"2026-05-09T22:31:12.648078Z","steps":["trace[951716896] 'agreement among raft nodes before linearized reading' (duration: 179.062985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.647423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.731231ms","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-05-09T22:31:12.64823Z","caller":"traceutil/trace.go:171","msg":"trace[1648318853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25426; }","duration":"222.55569ms","start":"2026-05-09T22:31:12.42565Z","end":"2026-05-09T22:31:12.648206Z","steps":["trace[1648318853] 'agreement among raft nodes before linearized reading' (duration: 221.645909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.647457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.214469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T22:31:12.648413Z","caller":"traceutil/trace.go:171","msg":"trace[1594587046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25426; }","duration":"264.146861ms","start":"2026-05-09T22:31:12.384233Z","end":"2026-05-09T22:31:12.64838Z","steps":["trace[1594587046] 'agreement among raft nodes before linearized reading' (duration: 262.664287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.647442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.173079ms","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-05-09T22:31:12.648531Z","caller":"traceutil/trace.go:171","msg":"trace[1106652388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25426; }","duration":"264.252823ms","start":"2026-05-09T22:31:12.384215Z","end":"2026-05-09T22:31:12.648468Z","steps":["trace[1106652388] 'agreement among raft nodes before linearized reading' (duration: 263.095596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.922432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.069498ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:31:12.922532Z","caller":"traceutil/trace.go:171","msg":"trace[1373728707] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"269.932389ms","start":"2026-05-09T22:31:12.652581Z","end":"2026-05-09T22:31:12.922514Z","steps":["trace[1373728707] 'process raft request' (duration: 113.700188ms)","trace[1373728707] 'compare' (duration: 155.807431ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:12.962926Z","caller":"traceutil/trace.go:171","msg":"trace[642968116] linearizableReadLoop","detail":"{readStateIndex:26238; appliedIndex:26233; }","duration":"255.845485ms","start":"2026-05-09T22:31:12.707063Z","end":"2026-05-09T22:31:12.962908Z","steps":["trace[642968116] 'read index received' (duration: 59.450877ms)","trace[642968116] 'applied index is now lower than readState.Index' (duration: 196.393457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:12.963047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.978547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:12.963074Z","caller":"traceutil/trace.go:171","msg":"trace[716506032] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25431; }","duration":"256.022818ms","start":"2026-05-09T22:31:12.707044Z","end":"2026-05-09T22:31:12.963066Z","steps":["trace[716506032] 'agreement among raft nodes before linearized reading' (duration: 255.940986ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:12.963341Z","caller":"traceutil/trace.go:171","msg":"trace[362700436] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"309.982453ms","start":"2026-05-09T22:31:12.653351Z","end":"2026-05-09T22:31:12.963333Z","steps":["trace[362700436] 'process raft request' (duration: 309.341388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.963406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:12.653298Z","time spent":"310.066115ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:31:12.963499Z","caller":"traceutil/trace.go:171","msg":"trace[1687717272] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"309.931382ms","start":"2026-05-09T22:31:12.653561Z","end":"2026-05-09T22:31:12.963493Z","steps":["trace[1687717272] 'process raft request' (duration: 309.269557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.963537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:12.653554Z","time spent":"309.963563ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:31:12.96368Z","caller":"traceutil/trace.go:171","msg":"trace[1275707264] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"310.023065ms","start":"2026-05-09T22:31:12.653618Z","end":"2026-05-09T22:31:12.963641Z","steps":["trace[1275707264] 'process raft request' (duration: 309.242496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.96385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:12.653613Z","time spent":"310.152278ms","remote":"127.0.0.1:41146","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-05-09T22:31:12.963707Z","caller":"traceutil/trace.go:171","msg":"trace[1999370409] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"310.028845ms","start":"2026-05-09T22:31:12.653649Z","end":"2026-05-09T22:31:12.963678Z","steps":["trace[1999370409] 'process raft request' (duration: 309.232186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:12.9642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:12.653645Z","time spent":"310.373023ms","remote":"127.0.0.1:41146","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-05-09T22:31:13.433833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.291528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:31:13.433949Z","caller":"traceutil/trace.go:171","msg":"trace[1286407261] linearizableReadLoop","detail":"{readStateIndex:26239; appliedIndex:26238; }","duration":"465.022226ms","start":"2026-05-09T22:31:12.968909Z","end":"2026-05-09T22:31:13.433932Z","steps":["trace[1286407261] 'read index received' (duration: 220.482192ms)","trace[1286407261] 'applied index is now lower than readState.Index' (duration: 244.538714ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:13.434027Z","caller":"traceutil/trace.go:171","msg":"trace[1421733344] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"494.349465ms","start":"2026-05-09T22:31:12.939667Z","end":"2026-05-09T22:31:13.434017Z","steps":["trace[1421733344] 'process raft request' (duration: 249.792061ms)","trace[1421733344] 'compare' (duration: 244.123204ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:13.43411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:12.939646Z","time spent":"494.415156ms","remote":"127.0.0.1:41146","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-05-09T22:31:13.434319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.429425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:13.434355Z","caller":"traceutil/trace.go:171","msg":"trace[312880928] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25432; }","duration":"465.496437ms","start":"2026-05-09T22:31:12.968849Z","end":"2026-05-09T22:31:13.434346Z","steps":["trace[312880928] 'agreement among raft nodes before linearized reading' (duration: 465.432376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:13.434385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:12.968832Z","time spent":"465.546848ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-09T22:31:13.4474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.761431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:13.447489Z","caller":"traceutil/trace.go:171","msg":"trace[1437732345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25433; }","duration":"302.894544ms","start":"2026-05-09T22:31:13.144577Z","end":"2026-05-09T22:31:13.447471Z","steps":["trace[1437732345] 'agreement among raft nodes before linearized reading' (duration: 302.761341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:13.447526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:13.144554Z","time spent":"302.963306ms","remote":"127.0.0.1:40912","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-05-09T22:31:13.447517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.773642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:13.447604Z","caller":"traceutil/trace.go:171","msg":"trace[790850824] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25433; }","duration":"213.891344ms","start":"2026-05-09T22:31:13.233687Z","end":"2026-05-09T22:31:13.447578Z","steps":["trace[790850824] 'agreement among raft nodes before linearized reading' (duration: 213.751101ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:13.447859Z","caller":"traceutil/trace.go:171","msg":"trace[478653114] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"119.63882ms","start":"2026-05-09T22:31:13.328202Z","end":"2026-05-09T22:31:13.447841Z","steps":["trace[478653114] 'process raft request' (duration: 118.882391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:13.448047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.277721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:13.448115Z","caller":"traceutil/trace.go:171","msg":"trace[366986441] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25433; }","duration":"140.347293ms","start":"2026-05-09T22:31:13.307756Z","end":"2026-05-09T22:31:13.448103Z","steps":["trace[366986441] 'agreement among raft nodes before linearized reading' (duration: 140.25061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:13.447879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.683742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:31:13.449063Z","caller":"traceutil/trace.go:171","msg":"trace[162562555] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25433; }","duration":"157.89525ms","start":"2026-05-09T22:31:13.291079Z","end":"2026-05-09T22:31:13.448974Z","steps":["trace[162562555] 'agreement among raft nodes before linearized reading' (duration: 156.231241ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:13.876569Z","caller":"traceutil/trace.go:171","msg":"trace[1136640510] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"132.144127ms","start":"2026-05-09T22:31:13.744399Z","end":"2026-05-09T22:31:13.876543Z","steps":["trace[1136640510] 'process raft request' (duration: 131.961393ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:13.910623Z","caller":"traceutil/trace.go:171","msg":"trace[1688557595] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"165.248337ms","start":"2026-05-09T22:31:13.745352Z","end":"2026-05-09T22:31:13.9106Z","steps":["trace[1688557595] 'process raft request' (duration: 165.117643ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:13.910813Z","caller":"traceutil/trace.go:171","msg":"trace[1136441433] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"162.342907ms","start":"2026-05-09T22:31:13.74844Z","end":"2026-05-09T22:31:13.910783Z","steps":["trace[1136441433] 'process raft request' (duration: 162.130532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:14.079231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.502766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-09T22:31:14.079327Z","caller":"traceutil/trace.go:171","msg":"trace[497870245] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:25436; }","duration":"111.64083ms","start":"2026-05-09T22:31:13.967667Z","end":"2026-05-09T22:31:14.079308Z","steps":["trace[497870245] 'range keys from in-memory index tree' (duration: 111.339783ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:14.203279Z","caller":"traceutil/trace.go:171","msg":"trace[155740712] linearizableReadLoop","detail":"{readStateIndex:26245; appliedIndex:26243; }","duration":"121.32947ms","start":"2026-05-09T22:31:14.081931Z","end":"2026-05-09T22:31:14.203261Z","steps":["trace[155740712] 'read index received' (duration: 33.833326ms)","trace[155740712] 'applied index is now lower than readState.Index' (duration: 87.495384ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:14.203356Z","caller":"traceutil/trace.go:171","msg":"trace[1414089142] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"109.784054ms","start":"2026-05-09T22:31:14.093546Z","end":"2026-05-09T22:31:14.20333Z","steps":["trace[1414089142] 'process raft request' (duration: 109.59794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:14.203483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.508714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:14.203536Z","caller":"traceutil/trace.go:171","msg":"trace[1662300123] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25438; }","duration":"121.627218ms","start":"2026-05-09T22:31:14.081894Z","end":"2026-05-09T22:31:14.203521Z","steps":["trace[1662300123] 'agreement among raft nodes before linearized reading' (duration: 121.449703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:19.408782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.243387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:31:19.409122Z","caller":"traceutil/trace.go:171","msg":"trace[395510100] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"418.776104ms","start":"2026-05-09T22:31:18.990307Z","end":"2026-05-09T22:31:19.409083Z","steps":["trace[395510100] 'process raft request' (duration: 284.148078ms)","trace[395510100] 'compare' (duration: 134.059773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:19.409294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:18.990295Z","time spent":"418.908968ms","remote":"127.0.0.1:41146","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-05-09T22:31:19.409287Z","caller":"traceutil/trace.go:171","msg":"trace[116837350] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"416.91629ms","start":"2026-05-09T22:31:18.992336Z","end":"2026-05-09T22:31:19.409252Z","steps":["trace[116837350] 'process raft request' (duration: 416.556282ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:19.409363Z","caller":"traceutil/trace.go:171","msg":"trace[2055756852] transaction","detail":"{read_only:false; response_revision:25475; number_of_response:1; }","duration":"416.9058ms","start":"2026-05-09T22:31:18.992418Z","end":"2026-05-09T22:31:19.409324Z","steps":["trace[2055756852] 'process raft request' (duration: 416.519761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:19.409416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:18.992326Z","time spent":"417.021793ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:31:19.409535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:18.9924Z","time spent":"417.043633ms","remote":"127.0.0.1:41146","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-05-09T22:31:19.518303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.051817ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-09T22:31:19.518805Z","caller":"traceutil/trace.go:171","msg":"trace[659150261] linearizableReadLoop","detail":"{readStateIndex:26282; appliedIndex:26280; }","duration":"526.335147ms","start":"2026-05-09T22:31:18.992428Z","end":"2026-05-09T22:31:19.518763Z","steps":["trace[659150261] 'read index received' (duration: 282.158961ms)","trace[659150261] 'applied index is now lower than readState.Index' (duration: 244.172596ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:19.51886Z","caller":"traceutil/trace.go:171","msg":"trace[1591896757] transaction","detail":"{read_only:false; response_revision:25476; number_of_response:1; }","duration":"397.422896ms","start":"2026-05-09T22:31:19.121409Z","end":"2026-05-09T22:31:19.518832Z","steps":["trace[1591896757] 'process raft request' (duration: 397.077558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:19.519015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:19.121384Z","time spent":"397.543289ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:31:19.646423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.955526ms","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-05-09T22:31:19.646528Z","caller":"traceutil/trace.go:171","msg":"trace[1710821873] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25476; }","duration":"654.108939ms","start":"2026-05-09T22:31:18.992392Z","end":"2026-05-09T22:31:19.646501Z","steps":["trace[1710821873] 'agreement among raft nodes before linearized reading' (duration: 526.52442ms)","trace[1710821873] 'range keys from in-memory index tree' (duration: 127.313022ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:19.646577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:18.992379Z","time spent":"654.188392ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T22:31:19.647717Z","caller":"traceutil/trace.go:171","msg":"trace[1354539431] transaction","detail":"{read_only:false; response_revision:25477; number_of_response:1; }","duration":"195.24842ms","start":"2026-05-09T22:31:19.452439Z","end":"2026-05-09T22:31:19.647687Z","steps":["trace[1354539431] 'process raft request' (duration: 115.05045ms)","trace[1354539431] 'compare' (duration: 78.93307ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:19.919091Z","caller":"traceutil/trace.go:171","msg":"trace[1702291246] linearizableReadLoop","detail":"{readStateIndex:26287; appliedIndex:26285; }","duration":"400.172411ms","start":"2026-05-09T22:31:19.518895Z","end":"2026-05-09T22:31:19.919067Z","steps":["trace[1702291246] 'read index received' (duration: 48.608697ms)","trace[1702291246] 'applied index is now lower than readState.Index' (duration: 351.562404ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:19.919129Z","caller":"traceutil/trace.go:171","msg":"trace[4753045] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"454.888265ms","start":"2026-05-09T22:31:19.464226Z","end":"2026-05-09T22:31:19.919114Z","steps":["trace[4753045] 'process raft request' (duration: 431.850297ms)","trace[4753045] 'compare' (duration: 22.67491ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:19.919222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.674261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T22:31:19.91923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:19.464204Z","time spent":"454.960617ms","remote":"127.0.0.1:41146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:31:19.919256Z","caller":"traceutil/trace.go:171","msg":"trace[1792577003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25478; }","duration":"771.758402ms","start":"2026-05-09T22:31:19.147487Z","end":"2026-05-09T22:31:19.919246Z","steps":["trace[1792577003] 'agreement among raft nodes before linearized reading' (duration: 771.686421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:19.919287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:19.147465Z","time spent":"771.816013ms","remote":"127.0.0.1:40912","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-05-09T22:31:19.919273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.174519ms","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:6"} {"level":"info","ts":"2026-05-09T22:31:19.919365Z","caller":"traceutil/trace.go:171","msg":"trace[462448493] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:25478; }","duration":"499.322023ms","start":"2026-05-09T22:31:19.420023Z","end":"2026-05-09T22:31:19.919345Z","steps":["trace[462448493] 'agreement among raft nodes before linearized reading' (duration: 499.096178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:19.919417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:31:19.420005Z","time spent":"499.401594ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-05-09T22:31:19.931051Z","caller":"traceutil/trace.go:171","msg":"trace[391222916] transaction","detail":"{read_only:false; response_revision:25479; number_of_response:1; }","duration":"279.20239ms","start":"2026-05-09T22:31:19.651833Z","end":"2026-05-09T22:31:19.931035Z","steps":["trace[391222916] 'process raft request' (duration: 279.101378ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:19.99839Z","caller":"traceutil/trace.go:171","msg":"trace[1663703692] transaction","detail":"{read_only:false; response_revision:25480; number_of_response:1; }","duration":"102.317247ms","start":"2026-05-09T22:31:19.896044Z","end":"2026-05-09T22:31:19.998361Z","steps":["trace[1663703692] 'process raft request' (duration: 101.982939ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:20.358252Z","caller":"traceutil/trace.go:171","msg":"trace[1202231498] linearizableReadLoop","detail":"{readStateIndex:26292; appliedIndex:26291; }","duration":"100.75048ms","start":"2026-05-09T22:31:20.257479Z","end":"2026-05-09T22:31:20.358229Z","steps":["trace[1202231498] 'read index received' (duration: 100.524173ms)","trace[1202231498] 'applied index is now lower than readState.Index' (duration: 225.257µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:20.358375Z","caller":"traceutil/trace.go:171","msg":"trace[1078566473] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"132.906546ms","start":"2026-05-09T22:31:20.225436Z","end":"2026-05-09T22:31:20.358343Z","steps":["trace[1078566473] 'process raft request' (duration: 132.633369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:20.358405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.886103ms","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-05-09T22:31:20.358557Z","caller":"traceutil/trace.go:171","msg":"trace[547734039] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25483; }","duration":"101.054086ms","start":"2026-05-09T22:31:20.257465Z","end":"2026-05-09T22:31:20.358519Z","steps":["trace[547734039] 'agreement among raft nodes before linearized reading' (duration: 100.858382ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:20.537035Z","caller":"traceutil/trace.go:171","msg":"trace[861156724] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"131.267536ms","start":"2026-05-09T22:31:20.405724Z","end":"2026-05-09T22:31:20.536992Z","steps":["trace[861156724] 'process raft request' (duration: 130.972919ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:20.763691Z","caller":"traceutil/trace.go:171","msg":"trace[1202548912] transaction","detail":"{read_only:false; response_revision:25485; number_of_response:1; }","duration":"100.098553ms","start":"2026-05-09T22:31:20.663559Z","end":"2026-05-09T22:31:20.763658Z","steps":["trace[1202548912] 'process raft request' (duration: 99.831337ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:20.894611Z","caller":"traceutil/trace.go:171","msg":"trace[1959750139] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"126.468792ms","start":"2026-05-09T22:31:20.768111Z","end":"2026-05-09T22:31:20.89458Z","steps":["trace[1959750139] 'process raft request' (duration: 57.622902ms)","trace[1959750139] 'compare' (duration: 68.479871ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:21.110423Z","caller":"traceutil/trace.go:171","msg":"trace[1120301957] transaction","detail":"{read_only:false; response_revision:25487; number_of_response:1; }","duration":"116.869914ms","start":"2026-05-09T22:31:20.993518Z","end":"2026-05-09T22:31:21.110388Z","steps":["trace[1120301957] 'process raft request' (duration: 52.941101ms)","trace[1120301957] 'compare' (duration: 63.79446ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:32:50.440285Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a50.wal"} {"level":"info","ts":"2026-05-09T22:33:21.000596Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23755} {"level":"info","ts":"2026-05-09T22:33:21.142158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23755,"took":"138.648101ms","hash":3787345490,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":37027840,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-09T22:33:21.14224Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3787345490,"revision":23755,"compact-revision":20475} {"level":"info","ts":"2026-05-09T22:37:55.910899Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:37:55.915835Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T22:37:55.915912Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T22:38:21.022243Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26677} {"level":"info","ts":"2026-05-09T22:38:21.167765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26677,"took":"140.719201ms","hash":1029014191,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":34562048,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-09T22:38:21.167836Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1029014191,"revision":26677,"compact-revision":23755} {"level":"info","ts":"2026-05-09T22:43:21.028381Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29278} {"level":"info","ts":"2026-05-09T22:43:21.169259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29278,"took":"136.387669ms","hash":1104346836,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":31133696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T22:43:21.169333Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1104346836,"revision":29278,"compact-revision":26677} {"level":"info","ts":"2026-05-09T22:48:21.034093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31536} {"level":"info","ts":"2026-05-09T22:48:21.165191Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31536,"took":"127.253961ms","hash":2981067003,"current-db-size-bytes":58220544,"current-db-size":"58 MB","current-db-size-in-use-bytes":31744000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T22:48:21.16529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2981067003,"revision":31536,"compact-revision":29278} {"level":"info","ts":"2026-05-09T22:53:21.039074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33909} {"level":"info","ts":"2026-05-09T22:53:21.188914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33909,"took":"144.968533ms","hash":3038265198,"current-db-size-bytes":59682816,"current-db-size":"60 MB","current-db-size-in-use-bytes":32321536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T22:53:21.188981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3038265198,"revision":33909,"compact-revision":31536} {"level":"info","ts":"2026-05-09T22:58:21.044321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36177} {"level":"info","ts":"2026-05-09T22:58:21.180663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36177,"took":"131.593465ms","hash":646258483,"current-db-size-bytes":59682816,"current-db-size":"60 MB","current-db-size-in-use-bytes":31866880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T22:58:21.180736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":646258483,"revision":36177,"compact-revision":33909} {"level":"info","ts":"2026-05-09T22:59:10.953141Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b6478f0ac5aeb8e1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:59:10.958961Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-09T22:59:10.95928Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}